builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-066 starttime: 1463524933.55 results: success (0) buildid: 20160517141544 builduid: 81be15bf541f44fab91cfd7ad9687eb1 revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.547970) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.548416) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.548742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.564852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.565160) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 15:42:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-17 15:42:13 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075242 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.654285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.654707) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019617 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.705617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:13.706017) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 15:42:13,926 truncating revision to first 12 chars 2016-05-17 15:42:13,926 Setting DEBUG logging. 2016-05-17 15:42:13,926 attempt 1/10 2016-05-17 15:42:13,926 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3f2fa1d7eed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 15:42:14,191 unpacking tar archive at: mozilla-central-f3f2fa1d7eed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.573448 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:14.292234) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:14.292589) ========= script_repo_revision: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:14.292996) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:14.293276) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 15:42:14.302790) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-17 15:42:14.303077) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:42:14 INFO - MultiFileLogger online at 20160517 15:42:14 in /builds/slave/test 15:42:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:42:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:14 INFO - {'append_to_log': False, 15:42:14 INFO - 'base_work_dir': '/builds/slave/test', 15:42:14 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:42:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:14 INFO - 'branch': 'Firefox-Non-PGO', 15:42:14 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:14 INFO - 'config_files': ('talos/linux_config.py',), 15:42:14 INFO - 'default_actions': ('clobber', 15:42:14 INFO - 'read-buildbot-config', 15:42:14 INFO - 'download-and-extract', 15:42:14 INFO - 'populate-webroot', 15:42:14 INFO - 'create-virtualenv', 15:42:14 INFO - 'install', 15:42:14 INFO - 'run-tests'), 15:42:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:14 INFO - 'download_minidump_stackwalk': True, 15:42:14 INFO - 'download_symbols': 'ondemand', 15:42:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:42:14 INFO - 'tooltool.py': '/tools/tooltool.py', 15:42:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:42:14 INFO - '/tools/misc-python/virtualenv.py')}, 15:42:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:14 INFO - 'installer_path': 'installer.exe', 15:42:14 INFO - 'log_level': 'info', 15:42:14 INFO - 'log_name': 'talos', 15:42:14 INFO - 'log_to_console': True, 15:42:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:42:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:42:14 INFO - 'opt_config_files': (), 15:42:14 INFO - 'pip_index': False, 15:42:14 INFO - 'sps_profile': False, 15:42:14 INFO - 'sps_profile_interval': 0, 15:42:14 INFO - 'suite': 'dromaeojs', 15:42:14 INFO - 'system_bits': '32', 15:42:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:14 INFO - 'title': 'talos-linux64-ix-066', 15:42:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:14 INFO - 'use_talos_json': True, 15:42:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:14 INFO - 'work_dir': 'build'} 15:42:14 INFO - ##### 15:42:14 INFO - ##### Running clobber step. 15:42:14 INFO - ##### 15:42:14 INFO - Running pre-action listener: _resource_record_pre_action 15:42:14 INFO - Running main action method: clobber 15:42:14 INFO - rmtree: /builds/slave/test/build 15:42:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:15 INFO - Running post-action listener: _resource_record_post_action 15:42:15 INFO - ##### 15:42:15 INFO - ##### Finished clobber step (success) 15:42:15 INFO - ##### 15:42:15 INFO - ##### 15:42:15 INFO - ##### Running read-buildbot-config step. 15:42:15 INFO - ##### 15:42:15 INFO - Running pre-action listener: _resource_record_pre_action 15:42:15 INFO - Running main action method: read_buildbot_config 15:42:15 INFO - Using buildbot properties: 15:42:15 INFO - { 15:42:15 INFO - "project": "", 15:42:15 INFO - "product": "firefox", 15:42:15 INFO - "script_repo_revision": "production", 15:42:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:42:15 INFO - "repository": "", 15:42:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:42:15 INFO - "buildid": "20160517141544", 15:42:15 INFO - "pgo_build": "False", 15:42:15 INFO - "basedir": "/builds/slave/test", 15:42:15 INFO - "buildnumber": 84, 15:42:15 INFO - "slavename": "talos-linux64-ix-066", 15:42:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:42:15 INFO - "platform": "ubuntu64_hw", 15:42:15 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:42:15 INFO - "branch": "mozilla-central", 15:42:15 INFO - "repo_path": "mozilla-central", 15:42:15 INFO - "revision": "f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e", 15:42:15 INFO - "stage_platform": "linux64", 15:42:15 INFO - "builduid": "81be15bf541f44fab91cfd7ad9687eb1", 15:42:15 INFO - "slavebuilddir": "test" 15:42:15 INFO - } 15:42:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:42:15 INFO - Running post-action listener: _resource_record_post_action 15:42:15 INFO - ##### 15:42:15 INFO - ##### Finished read-buildbot-config step (success) 15:42:15 INFO - ##### 15:42:15 INFO - ##### 15:42:15 INFO - ##### Running download-and-extract step. 15:42:15 INFO - ##### 15:42:15 INFO - Running pre-action listener: _resource_record_pre_action 15:42:15 INFO - Running main action method: download_and_extract 15:42:15 INFO - mkdir: /builds/slave/test/build/tests 15:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:15 INFO - https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:42:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:42:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:42:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:42:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:42:16 INFO - Downloaded 1361 bytes. 15:42:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:42:16 INFO - Using the following test package requirements: 15:42:16 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:42:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:42:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:42:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'jsshell-linux-x86_64.zip'], 15:42:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:42:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:42:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:42:16 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:42:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:42:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:42:16 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:42:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:16 INFO - https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:42:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:42:17 INFO - Downloaded 22450783 bytes. 15:42:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:42:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:42:18 INFO - Return code: 0 15:42:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:18 INFO - https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:42:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:42:20 INFO - Downloaded 22450783 bytes. 15:42:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:42:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:42:21 INFO - Return code: 0 15:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:21 INFO - https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:42:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:42:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:42:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:42:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:42:22 INFO - Downloaded 10840194 bytes. 15:42:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:42:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:42:23 INFO - Return code: 0 15:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:23 INFO - https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:42:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:42:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:42:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:42:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:42:24 INFO - Downloaded 54761543 bytes. 15:42:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:42:24 INFO - mkdir: /builds/slave/test/properties 15:42:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:42:24 INFO - Writing to file /builds/slave/test/properties/build_url 15:42:24 INFO - Contents: 15:42:24 INFO - build_url:https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:42:25 INFO - Running post-action listener: _resource_record_post_action 15:42:25 INFO - Running post-action listener: set_extra_try_arguments 15:42:25 INFO - ##### 15:42:25 INFO - ##### Finished download-and-extract step (success) 15:42:25 INFO - ##### 15:42:25 INFO - ##### 15:42:25 INFO - ##### Running populate-webroot step. 15:42:25 INFO - ##### 15:42:25 INFO - Running pre-action listener: _resource_record_pre_action 15:42:25 INFO - Running main action method: populate_webroot 15:42:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:42:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:42:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:42:25 INFO - '--tppagecycles', 15:42:25 INFO - '7'], 15:42:25 INFO - 'tests': ['tsvgm']}}, 15:42:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:42:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:42:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:42:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:42:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['damp', 'tps']}, 15:42:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'tests': ['damp', 'tps']}, 15:42:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['dromaeo_dom']}, 15:42:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:42:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'other-e10s': {'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['a11yr', 15:42:25 INFO - 'ts_paint', 15:42:25 INFO - 'tpaint', 15:42:25 INFO - 'sessionrestore', 15:42:25 INFO - 'sessionrestore_no_auto_restore', 15:42:25 INFO - 'tabpaint']}, 15:42:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['tsvgx', 15:42:25 INFO - 'tsvgr_opacity', 15:42:25 INFO - 'tart', 15:42:25 INFO - 'tscrollx', 15:42:25 INFO - 'cart']}, 15:42:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:42:25 INFO - 'tsvgr_opacity', 15:42:25 INFO - 'tart', 15:42:25 INFO - 'tscrollx', 15:42:25 INFO - 'cart']}, 15:42:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'talos_options': ['--disable-e10s'], 15:42:25 INFO - 'tests': ['tp5o']}, 15:42:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'tests': ['tp5o']}, 15:42:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'talos_options': ['--disable-e10s', 15:42:25 INFO - '--xperf_path', 15:42:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:25 INFO - 'tests': ['tp5n']}, 15:42:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:25 INFO - 'talos_options': ['--xperf_path', 15:42:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:25 INFO - 'tests': ['tp5n']}}, 15:42:25 INFO - 'talos.zip': {'path': '', 15:42:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:42:25 INFO - Running post-action listener: _resource_record_post_action 15:42:25 INFO - ##### 15:42:25 INFO - ##### Finished populate-webroot step (success) 15:42:25 INFO - ##### 15:42:25 INFO - ##### 15:42:25 INFO - ##### Running create-virtualenv step. 15:42:25 INFO - ##### 15:42:25 INFO - Running pre-action listener: _resource_record_pre_action 15:42:25 INFO - Running main action method: create_virtualenv 15:42:25 INFO - Creating virtualenv /builds/slave/test/build/venv 15:42:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:42:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:42:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:42:25 INFO - Using real prefix '/usr' 15:42:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:42:26 INFO - Installing distribute.............................................................................................................................................................................................done. 15:42:28 INFO - Installing pip.................done. 15:42:28 INFO - Return code: 0 15:42:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:42:28 INFO - Using env: {'DISPLAY': ':0', 15:42:28 INFO - 'HOME': '/home/cltbld', 15:42:28 INFO - 'LANG': 'en_US.UTF-8', 15:42:28 INFO - 'LANGUAGE': 'en_US:en', 15:42:28 INFO - 'LOGNAME': 'cltbld', 15:42:28 INFO - 'MAIL': '/var/mail/cltbld', 15:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:28 INFO - 'MOZ_NO_REMOTE': '1', 15:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:28 INFO - 'NO_EM_RESTART': '1', 15:42:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:28 INFO - 'PWD': '/builds/slave/test', 15:42:28 INFO - 'SHELL': '/bin/bash', 15:42:28 INFO - 'SHLVL': '1', 15:42:28 INFO - 'TERM': 'linux', 15:42:28 INFO - 'TMOUT': '86400', 15:42:28 INFO - 'USER': 'cltbld', 15:42:28 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:28 INFO - '_': '/tools/buildbot/bin/python'} 15:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:42:28 INFO - Cleaning up... 15:42:28 INFO - Return code: 0 15:42:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:42:28 INFO - Using env: {'DISPLAY': ':0', 15:42:28 INFO - 'HOME': '/home/cltbld', 15:42:28 INFO - 'LANG': 'en_US.UTF-8', 15:42:28 INFO - 'LANGUAGE': 'en_US:en', 15:42:28 INFO - 'LOGNAME': 'cltbld', 15:42:28 INFO - 'MAIL': '/var/mail/cltbld', 15:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:28 INFO - 'MOZ_NO_REMOTE': '1', 15:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:28 INFO - 'NO_EM_RESTART': '1', 15:42:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:28 INFO - 'PWD': '/builds/slave/test', 15:42:28 INFO - 'SHELL': '/bin/bash', 15:42:28 INFO - 'SHLVL': '1', 15:42:28 INFO - 'TERM': 'linux', 15:42:28 INFO - 'TMOUT': '86400', 15:42:28 INFO - 'USER': 'cltbld', 15:42:28 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:28 INFO - '_': '/tools/buildbot/bin/python'} 15:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:28 INFO - Downloading/unpacking psutil>=3.1.1 15:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:42:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:30 INFO - Installing collected packages: psutil 15:42:30 INFO - Running setup.py install for psutil 15:42:30 INFO - building 'psutil._psutil_linux' extension 15:42:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:42:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:42:30 INFO - building 'psutil._psutil_posix' extension 15:42:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:42:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:42:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:31 INFO - Successfully installed psutil 15:42:31 INFO - Cleaning up... 15:42:31 INFO - Return code: 0 15:42:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:42:31 INFO - Using env: {'DISPLAY': ':0', 15:42:31 INFO - 'HOME': '/home/cltbld', 15:42:31 INFO - 'LANG': 'en_US.UTF-8', 15:42:31 INFO - 'LANGUAGE': 'en_US:en', 15:42:31 INFO - 'LOGNAME': 'cltbld', 15:42:31 INFO - 'MAIL': '/var/mail/cltbld', 15:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:31 INFO - 'MOZ_NO_REMOTE': '1', 15:42:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:31 INFO - 'NO_EM_RESTART': '1', 15:42:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:31 INFO - 'PWD': '/builds/slave/test', 15:42:31 INFO - 'SHELL': '/bin/bash', 15:42:31 INFO - 'SHLVL': '1', 15:42:31 INFO - 'TERM': 'linux', 15:42:31 INFO - 'TMOUT': '86400', 15:42:31 INFO - 'USER': 'cltbld', 15:42:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:31 INFO - '_': '/tools/buildbot/bin/python'} 15:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:42:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:42:33 INFO - Installing collected packages: mozsystemmonitor 15:42:33 INFO - Running setup.py install for mozsystemmonitor 15:42:33 INFO - Successfully installed mozsystemmonitor 15:42:33 INFO - Cleaning up... 15:42:33 INFO - Return code: 0 15:42:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:42:33 INFO - Using env: {'DISPLAY': ':0', 15:42:33 INFO - 'HOME': '/home/cltbld', 15:42:33 INFO - 'LANG': 'en_US.UTF-8', 15:42:33 INFO - 'LANGUAGE': 'en_US:en', 15:42:33 INFO - 'LOGNAME': 'cltbld', 15:42:33 INFO - 'MAIL': '/var/mail/cltbld', 15:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:33 INFO - 'MOZ_NO_REMOTE': '1', 15:42:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:33 INFO - 'NO_EM_RESTART': '1', 15:42:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:33 INFO - 'PWD': '/builds/slave/test', 15:42:33 INFO - 'SHELL': '/bin/bash', 15:42:33 INFO - 'SHLVL': '1', 15:42:33 INFO - 'TERM': 'linux', 15:42:33 INFO - 'TMOUT': '86400', 15:42:33 INFO - 'USER': 'cltbld', 15:42:33 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:33 INFO - '_': '/tools/buildbot/bin/python'} 15:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:33 INFO - Downloading/unpacking blobuploader==1.2.4 15:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:36 INFO - Downloading docopt-0.6.1.tar.gz 15:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:36 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:36 INFO - Running setup.py install for blobuploader 15:42:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:36 INFO - Running setup.py install for requests 15:42:36 INFO - Running setup.py install for docopt 15:42:36 INFO - Successfully installed blobuploader requests docopt 15:42:36 INFO - Cleaning up... 15:42:36 INFO - Return code: 0 15:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:36 INFO - Using env: {'DISPLAY': ':0', 15:42:36 INFO - 'HOME': '/home/cltbld', 15:42:36 INFO - 'LANG': 'en_US.UTF-8', 15:42:36 INFO - 'LANGUAGE': 'en_US:en', 15:42:36 INFO - 'LOGNAME': 'cltbld', 15:42:36 INFO - 'MAIL': '/var/mail/cltbld', 15:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:36 INFO - 'MOZ_NO_REMOTE': '1', 15:42:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:36 INFO - 'NO_EM_RESTART': '1', 15:42:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:36 INFO - 'PWD': '/builds/slave/test', 15:42:36 INFO - 'SHELL': '/bin/bash', 15:42:36 INFO - 'SHLVL': '1', 15:42:36 INFO - 'TERM': 'linux', 15:42:36 INFO - 'TMOUT': '86400', 15:42:36 INFO - 'USER': 'cltbld', 15:42:36 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:36 INFO - '_': '/tools/buildbot/bin/python'} 15:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:37 INFO - Running setup.py (path:/tmp/pip-cjUS1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:37 INFO - Running setup.py (path:/tmp/pip-xSiBfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:37 INFO - Running setup.py (path:/tmp/pip-kNMXsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:37 INFO - Running setup.py (path:/tmp/pip-fd02DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:37 INFO - Running setup.py (path:/tmp/pip-sYkwYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:37 INFO - Running setup.py (path:/tmp/pip-2JJs0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:37 INFO - Running setup.py (path:/tmp/pip-amk532-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:37 INFO - Running setup.py (path:/tmp/pip-XRIo0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:37 INFO - Running setup.py (path:/tmp/pip-itDeTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:37 INFO - Running setup.py (path:/tmp/pip-usFEUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:37 INFO - Running setup.py (path:/tmp/pip-0LR3K3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:37 INFO - Running setup.py (path:/tmp/pip-FOWNoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:38 INFO - Running setup.py (path:/tmp/pip-fK5KoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:38 INFO - Running setup.py (path:/tmp/pip-KACqF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:38 INFO - Running setup.py (path:/tmp/pip-vR0kqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:38 INFO - Running setup.py (path:/tmp/pip-A4jGm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:38 INFO - Running setup.py (path:/tmp/pip-UGxc_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:38 INFO - Running setup.py install for manifestparser 15:42:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:42:38 INFO - Running setup.py install for mozcrash 15:42:38 INFO - Running setup.py install for mozdebug 15:42:38 INFO - Running setup.py install for mozdevice 15:42:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:42:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:42:38 INFO - Running setup.py install for mozfile 15:42:38 INFO - Running setup.py install for mozhttpd 15:42:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozinfo 15:42:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozInstall 15:42:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozleak 15:42:39 INFO - Running setup.py install for mozlog 15:42:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for moznetwork 15:42:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozprocess 15:42:39 INFO - Running setup.py install for mozprofile 15:42:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozrunner 15:42:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:42:39 INFO - Running setup.py install for mozscreenshot 15:42:39 INFO - Running setup.py install for moztest 15:42:40 INFO - Running setup.py install for mozversion 15:42:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:42:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:40 INFO - Cleaning up... 15:42:40 INFO - Return code: 0 15:42:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:40 INFO - Using env: {'DISPLAY': ':0', 15:42:40 INFO - 'HOME': '/home/cltbld', 15:42:40 INFO - 'LANG': 'en_US.UTF-8', 15:42:40 INFO - 'LANGUAGE': 'en_US:en', 15:42:40 INFO - 'LOGNAME': 'cltbld', 15:42:40 INFO - 'MAIL': '/var/mail/cltbld', 15:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:40 INFO - 'MOZ_NO_REMOTE': '1', 15:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:40 INFO - 'NO_EM_RESTART': '1', 15:42:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:40 INFO - 'PWD': '/builds/slave/test', 15:42:40 INFO - 'SHELL': '/bin/bash', 15:42:40 INFO - 'SHLVL': '1', 15:42:40 INFO - 'TERM': 'linux', 15:42:40 INFO - 'TMOUT': '86400', 15:42:40 INFO - 'USER': 'cltbld', 15:42:40 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:40 INFO - '_': '/tools/buildbot/bin/python'} 15:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:40 INFO - Running setup.py (path:/tmp/pip-7MgYUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:40 INFO - Running setup.py (path:/tmp/pip-KbMqiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:40 INFO - Running setup.py (path:/tmp/pip-JbUCon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:40 INFO - Running setup.py (path:/tmp/pip-Ia5NWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:40 INFO - Running setup.py (path:/tmp/pip-sdy_vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:40 INFO - Running setup.py (path:/tmp/pip-u96ejd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:40 INFO - Running setup.py (path:/tmp/pip-JupD7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:40 INFO - Running setup.py (path:/tmp/pip-d7kGRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:40 INFO - Running setup.py (path:/tmp/pip-wDWQxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:41 INFO - Running setup.py (path:/tmp/pip-dENIle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:41 INFO - Running setup.py (path:/tmp/pip-hW8PcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:41 INFO - Running setup.py (path:/tmp/pip-6V8v4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:41 INFO - Running setup.py (path:/tmp/pip-QJ5dLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:41 INFO - Running setup.py (path:/tmp/pip-TrTJtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:41 INFO - Running setup.py (path:/tmp/pip-rsePgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:41 INFO - Running setup.py (path:/tmp/pip-Pp8MhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:42:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:41 INFO - Running setup.py (path:/tmp/pip-wgO8Uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:43 INFO - Downloading blessings-1.6.tar.gz 15:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:42:43 INFO - Installing collected packages: blessings 15:42:43 INFO - Running setup.py install for blessings 15:42:43 INFO - Successfully installed blessings 15:42:43 INFO - Cleaning up... 15:42:43 INFO - Return code: 0 15:42:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:43 INFO - Reading from file tmpfile_stdout 15:42:43 INFO - Current package versions: 15:42:43 INFO - argparse == 1.2.1 15:42:43 INFO - blessings == 1.6 15:42:43 INFO - blobuploader == 1.2.4 15:42:43 INFO - docopt == 0.6.1 15:42:43 INFO - manifestparser == 1.1 15:42:43 INFO - mozInstall == 1.12 15:42:43 INFO - mozcrash == 0.17 15:42:43 INFO - mozdebug == 0.1 15:42:43 INFO - mozdevice == 0.48 15:42:43 INFO - mozfile == 1.2 15:42:43 INFO - mozhttpd == 0.7 15:42:43 INFO - mozinfo == 0.9 15:42:43 INFO - mozleak == 0.1 15:42:43 INFO - mozlog == 3.2 15:42:43 INFO - moznetwork == 0.27 15:42:43 INFO - mozprocess == 0.22 15:42:43 INFO - mozprofile == 0.28 15:42:43 INFO - mozrunner == 6.11 15:42:43 INFO - mozscreenshot == 0.1 15:42:43 INFO - mozsystemmonitor == 0.3 15:42:43 INFO - moztest == 0.7 15:42:43 INFO - mozversion == 1.4 15:42:43 INFO - psutil == 3.1.1 15:42:43 INFO - requests == 1.2.3 15:42:43 INFO - wsgiref == 0.1.2 15:42:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:43 INFO - Using env: {'DISPLAY': ':0', 15:42:43 INFO - 'HOME': '/home/cltbld', 15:42:43 INFO - 'LANG': 'en_US.UTF-8', 15:42:43 INFO - 'LANGUAGE': 'en_US:en', 15:42:43 INFO - 'LOGNAME': 'cltbld', 15:42:43 INFO - 'MAIL': '/var/mail/cltbld', 15:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:43 INFO - 'MOZ_NO_REMOTE': '1', 15:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:43 INFO - 'NO_EM_RESTART': '1', 15:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:43 INFO - 'PWD': '/builds/slave/test', 15:42:43 INFO - 'SHELL': '/bin/bash', 15:42:43 INFO - 'SHLVL': '1', 15:42:43 INFO - 'TERM': 'linux', 15:42:43 INFO - 'TMOUT': '86400', 15:42:43 INFO - 'USER': 'cltbld', 15:42:43 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:43 INFO - '_': '/tools/buildbot/bin/python'} 15:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:42:44 INFO - Cleaning up... 15:42:44 INFO - Return code: 0 15:42:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:42:44 INFO - Using env: {'DISPLAY': ':0', 15:42:44 INFO - 'HOME': '/home/cltbld', 15:42:44 INFO - 'LANG': 'en_US.UTF-8', 15:42:44 INFO - 'LANGUAGE': 'en_US:en', 15:42:44 INFO - 'LOGNAME': 'cltbld', 15:42:44 INFO - 'MAIL': '/var/mail/cltbld', 15:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:44 INFO - 'MOZ_NO_REMOTE': '1', 15:42:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:44 INFO - 'NO_EM_RESTART': '1', 15:42:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:44 INFO - 'PWD': '/builds/slave/test', 15:42:44 INFO - 'SHELL': '/bin/bash', 15:42:44 INFO - 'SHLVL': '1', 15:42:44 INFO - 'TERM': 'linux', 15:42:44 INFO - 'TMOUT': '86400', 15:42:44 INFO - 'USER': 'cltbld', 15:42:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:44 INFO - '_': '/tools/buildbot/bin/python'} 15:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:44 INFO - Downloading/unpacking jsonschema 15:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:46 INFO - Downloading/unpacking functools32 (from jsonschema) 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:46 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:46 INFO - warning: no files found matching '*.txt' 15:42:46 INFO - no previously-included directories found matching 'build' 15:42:46 INFO - no previously-included directories found matching 'dist' 15:42:46 INFO - no previously-included directories found matching '.git*' 15:42:46 INFO - Installing collected packages: jsonschema, functools32 15:42:46 INFO - Running setup.py install for functools32 15:42:46 INFO - warning: no files found matching '*.txt' 15:42:46 INFO - no previously-included directories found matching 'build' 15:42:46 INFO - no previously-included directories found matching 'dist' 15:42:46 INFO - no previously-included directories found matching '.git*' 15:42:46 INFO - Successfully installed jsonschema functools32 15:42:46 INFO - Cleaning up... 15:42:46 INFO - Return code: 0 15:42:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250c4fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1750770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:42:46 INFO - Using env: {'DISPLAY': ':0', 15:42:46 INFO - 'HOME': '/home/cltbld', 15:42:46 INFO - 'LANG': 'en_US.UTF-8', 15:42:46 INFO - 'LANGUAGE': 'en_US:en', 15:42:46 INFO - 'LOGNAME': 'cltbld', 15:42:46 INFO - 'MAIL': '/var/mail/cltbld', 15:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:46 INFO - 'MOZ_NO_REMOTE': '1', 15:42:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:46 INFO - 'NO_EM_RESTART': '1', 15:42:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:46 INFO - 'PWD': '/builds/slave/test', 15:42:46 INFO - 'SHELL': '/bin/bash', 15:42:46 INFO - 'SHLVL': '1', 15:42:46 INFO - 'TERM': 'linux', 15:42:46 INFO - 'TMOUT': '86400', 15:42:46 INFO - 'USER': 'cltbld', 15:42:46 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:46 INFO - '_': '/tools/buildbot/bin/python'} 15:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:46 INFO - Downloading/unpacking flake8 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:42:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:42:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:42:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:42:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:42:49 INFO - no previously-included directories found matching 'docs/_build' 15:42:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:42:49 INFO - Running setup.py install for pep8 15:42:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:42:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:42:49 INFO - no previously-included directories found matching 'docs/_build' 15:42:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:42:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:42:49 INFO - Cleaning up... 15:42:49 INFO - Return code: 0 15:42:49 INFO - Running post-action listener: _resource_record_post_action 15:42:49 INFO - Running post-action listener: _start_resource_monitoring 15:42:49 INFO - Starting resource monitoring. 15:42:49 INFO - ##### 15:42:49 INFO - ##### Finished create-virtualenv step (success) 15:42:49 INFO - ##### 15:42:49 INFO - ##### 15:42:49 INFO - ##### Running install step. 15:42:49 INFO - ##### 15:42:49 INFO - Running pre-action listener: _resource_record_pre_action 15:42:49 INFO - Running main action method: install 15:42:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:50 INFO - Reading from file tmpfile_stdout 15:42:50 INFO - Detecting whether we're running mozinstall >=1.0... 15:42:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:42:50 INFO - Reading from file tmpfile_stdout 15:42:50 INFO - Output received: 15:42:50 INFO - Usage: mozinstall [options] installer 15:42:50 INFO - Options: 15:42:50 INFO - -h, --help show this help message and exit 15:42:50 INFO - -d DEST, --destination=DEST 15:42:50 INFO - Directory to install application into. [default: 15:42:50 INFO - "/builds/slave/test"] 15:42:50 INFO - --app=APP Application being installed. [default: firefox] 15:42:50 INFO - mkdir: /builds/slave/test/build/application 15:42:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:43:05 INFO - Reading from file tmpfile_stdout 15:43:05 INFO - Output received: 15:43:05 INFO - /builds/slave/test/build/application/firefox/firefox 15:43:05 INFO - Running post-action listener: _resource_record_post_action 15:43:05 INFO - ##### 15:43:05 INFO - ##### Finished install step (success) 15:43:05 INFO - ##### 15:43:05 INFO - ##### 15:43:05 INFO - ##### Running run-tests step. 15:43:05 INFO - ##### 15:43:05 INFO - Running pre-action listener: _resource_record_pre_action 15:43:05 INFO - Running main action method: run_tests 15:43:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:43:05 INFO - Python 2.7.3 15:43:05 INFO - Return code: 0 15:43:05 INFO - grabbing minidump binary from tooltool 15:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:43:05 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17df9c0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17e08c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17e0d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:43:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:43:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:43:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:43:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:43:06 INFO - Return code: 0 15:43:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:43:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:43:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:43:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:43:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:43:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:43:06 INFO - Return code: 0 15:43:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:43:06 INFO - Using env: {'DISPLAY': ':0', 15:43:06 INFO - 'HOME': '/home/cltbld', 15:43:06 INFO - 'LANG': 'en_US.UTF-8', 15:43:06 INFO - 'LANGUAGE': 'en_US:en', 15:43:06 INFO - 'LOGNAME': 'cltbld', 15:43:06 INFO - 'MAIL': '/var/mail/cltbld', 15:43:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:06 INFO - 'MOZ_NO_REMOTE': '1', 15:43:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:06 INFO - 'NO_EM_RESTART': '1', 15:43:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:06 INFO - 'PWD': '/builds/slave/test', 15:43:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:06 INFO - 'SHELL': '/bin/bash', 15:43:06 INFO - 'SHLVL': '1', 15:43:06 INFO - 'TERM': 'linux', 15:43:06 INFO - 'TMOUT': '86400', 15:43:06 INFO - 'USER': 'cltbld', 15:43:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549', 15:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:06 INFO - '_': '/tools/buildbot/bin/python'} 15:43:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:43:07 INFO - mozversion application_buildid: 20160517141544 15:43:07 INFO - mozversion application_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 15:43:07 INFO - mozversion application_display_name: Nightly 15:43:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:43:07 INFO - mozversion application_name: Firefox 15:43:07 INFO - mozversion application_remotingname: firefox 15:43:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:43:07 INFO - mozversion application_vendor: Mozilla 15:43:07 INFO - mozversion application_version: 49.0a1 15:43:07 INFO - mozversion platform_buildid: 20160517141544 15:43:07 INFO - mozversion platform_changeset: f3f2fa1d7eed5a8262f6401ef18ff8117a3ce43e 15:43:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:43:07 INFO - mozversion platform_version: 49.0a1 15:43:07 INFO - using testdate: 1463524987 15:43:07 INFO - actual date: 1463524987 15:43:07 INFO - starting webserver on 'localhost:51874' 15:43:07 INFO - SUITE-START | Running 2 tests 15:43:07 INFO - TEST-START | dromaeo_css 15:43:07 INFO - operating with platform_type : linux_ 15:43:07 INFO - Initialising browser for dromaeo_css test... 15:43:07 INFO - TEST-INFO | started process 1469 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFt53Th/profile http://localhost:51874/getInfo.html) 15:43:11 INFO - PROCESS | 1469 | __metrics Screen width/height:1600/1200 15:43:11 INFO - PROCESS | 1469 | colorDepth:24 15:43:11 INFO - PROCESS | 1469 | Browser inner width/height: 1024/697 15:43:11 INFO - PROCESS | 1469 | __metrics 15:43:12 ERROR - PROCESS | 1469 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:43:13 INFO - TEST-INFO | 1469: exit 0 15:43:13 INFO - Browser initialized. 15:43:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:43:13 INFO - TEST-INFO | started process 1552 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFt53Th/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:25 INFO - PROCESS | 1552 | RSS: Main: 153903104 15:43:25 INFO - PROCESS | 1552 | 15:44:16 INFO - PROCESS | 1552 | 1463525056434 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:46:29 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51874/tests/dromaeo/cssquery-ext.html) 15:46:30 INFO - PROCESS | 1552 | RSS: Main: 261308416 15:46:30 INFO - PROCESS | 1552 | 15:49:33 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-ext.html (next: http://localhost:51874/tests/dromaeo/cssquery-jquery.html) 15:49:33 INFO - PROCESS | 1552 | RSS: Main: 237256704 15:49:33 INFO - PROCESS | 1552 | 15:52:36 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51874/tests/dromaeo/cssquery-mootools.html) 15:52:36 INFO - PROCESS | 1552 | RSS: Main: 238714880 15:52:36 INFO - PROCESS | 1552 | 15:55:38 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51874/tests/dromaeo/cssquery-prototype.html) 15:55:39 INFO - PROCESS | 1552 | RSS: Main: 229163008 15:55:39 INFO - PROCESS | 1552 | 15:58:42 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51874/tests/dromaeo/cssquery-yui.html) 15:58:42 INFO - PROCESS | 1552 | RSS: Main: 215330816 15:58:42 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | Cycle 1(1): loaded http://localhost:51874/tests/dromaeo/cssquery-yui.html (next: http://localhost:51874/tests/dromaeo/cssquery-dojo.html) 16:01:45 INFO - PROCESS | 1552 | RSS: Main: 270569472 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | __start_tp_report 16:01:45 INFO - PROCESS | 1552 | _x_x_mozilla_page_load 16:01:45 INFO - PROCESS | 1552 | _x_x_mozilla_page_load_details 16:01:45 INFO - PROCESS | 1552 | |i|pagename|runs| 16:01:45 INFO - PROCESS | 1552 | |0;dojo.html;2666.6799984001923;2682.959755603665;2716.3209197700576;2718.2388931099285;2728.7271272872717;4647.395838540985;4707.552782485667;4712.269598212274;4745.762711864401;4817.132916075105;4744.501827308135;4756.619470442365;4784.49762774908;4806.495317991613;4808.278758186265;4401.66987475939;4416.624586910117;4449.2881139017845;4466.932996005063;4475.820967161326;4392.407025051625;4482.641388688897;4484.753338566394;4515.796789144496;4559.042601053783;4496.9325460118125;4557.977210113945;4582.9770851145695;4583.427071616048;4593.28804035375;4273.401723758676;4326.09152078064;4375.978120109411;4388.934165987513;4456.5766252205985;4340.045190058182;4340.8480703175555;4354.216241076605;4391.099824535994;4404.2512772828695;4741.431028276595;4747.976260118678;4748.19280722278;4760.071786001731;4763.666543341968;4983.377077865267;5032.521910418504;5113.846584602468;5116.769745361467;5170.9741451292875;5137.38351397831;5203.245529398254;5206.921896171561;5209.2446595243855;5210.921836172461;4815.518448155227;4833.05755377705;4860.8298709545;4868.294097355863;4872.561469467765;5057.570106540912;5183.144781111075;5261.973690131525;5262.315898933114;5296.25852380667;5030.0694371541385;5067.923981140286;5094.592432605456;5126.333576635088;5127.461616530285;4934.55588996992;5101.974490127525;5118.8976220476125;5125.7513681629325;5129.743512824344;5088.898222035538;5090.160123579568;5112.948870511322;5156.845294641167;5157.8452646420665;5086.745662716923;5086.821961231339;5089.211172268304;5093.516115968977;5138.3577052868395;4198.6221240088535;4247.086876321606;4297.226499230169;4318.762468064287;4354.172707185625;3701.5373078365205;3750.624937506282;3750.7374483785875;3774.471573979645;3783.6027217142355;4388.166248412791;4407.228734971367;4413.360062790877;4414.426124603781;4459.175052615276;5010.774515146827;5086.16078347076;5113.309703190096;5122.206058061007;5175.870603234874;4386.429764130707;4420.248557745191;4430.8449204277695;4465.129299786576;4473.060657262012;4637.3275874997935;4646.79089440976;4655.348251244828;4660.487346391894;4704.647151463654;4706.411698537683;4820.156472617348;4886.438059623189;4891.413030436371;4893.388326459192;5145.228215767665;5165;5177.741112944263;5233.764480598458;5234.371875374979;4880.682755620874;4893.657443978887;4897.779599917941;4901.3383193268455;4947.45577986414;4900.509949005071;5034.44621091687;5061.9746901266735;5100.719460429712;5111.846644600668;5135.409427915837;5144.562712169358;5166.948330516797;5184.144616138295;5232.738363081907;4987.750612469435;4991.460141517557;5029.723365214948;5031.547160755549;5056.974715126401;4819.445763737215;4924.803007879645;4955.45489996093;5019.121653710513;5031.698098114125;4934.309196712535;4949.82675606352;4973.9253911190635;4978.502149785138;4986.1772807487805;5049.192129259301;5053.570446512088;5058.190689489664;5060.2156665716875;5094;4811.879703007453;4871.585915197106;4912.042151780369;4926.088673595396;4990.50094990498 16:01:45 INFO - PROCESS | 1552 | |1;ext.html;552.9797523569016;591.8372447576916;600.7086563016936;604.7429842317017;605.1074664869321;28400.289992750193;41851;42454.787726061404;42584.14831703364;42670.35994460078;43652.345214821806;44181.77909110458;44274.778626106905;44301.11397772043;44354.33468497975;8005.679772809095;8162.591870406485;8170.877436838452;8176.264136227738;8178.591070446483;8001.679932802681;8083.3129184019135;8089.716859909904;8090.190980901922;8091.4740541864885;8065.798355041112;8097.2307630774985;8117.310028647572;8122.309603683694;8135.145809689957;8031.076426210973;8058.717944871902;8080.878786818203;8096.514209147411;8110.553919534424;7962.442629015971;8038.758837234892;8054.959725201336;8056.476329038594;8059.879101813477;40013.19973600541;40303.59696403028;40410.1917961642;40418.39372409416;40428.19143617111;3077.538369244609;3127.8279694616785;3141.701538353853;3226.2095786532213;3349.949750753741;30293.394132117453;30640.693593064007;31215.063548093593;31463;32427;3463.3939060664284;3501.9824900875587;3509.175343794206;3515.156362473014;3653.2510835278704;189198;189973;260194;261442;261484;52125.47874521282;52371;52623;52658.21012684813;52755.20867186995;34818.65181348154;35488.46767298492;35528.289434211685;37727.81136094302;61660.69169654123;12061.396930153458;12091.274523528617;12099.818502722466;12103;12123.333216673167;9888.159506441889;9994.500274986221;9997.950010249902;10004.849927251096;10010.449425281533;19652.855543113033;19749.60500789976;19761.604767904562;19798.20807167697;19804.306849260494;7250.492465527468;7274.49078564506;7339.88990165148;7369.557826530318;7403.111626604842;813.6623301329881;926.2080920812742;985.9950700246453;1007.1791489935679;1025.43601019439;28785;29598.556021659693;29618.851905740765;29828.701712983027;30091.849540752155;987.1757082835769;995.4425521707867;1040.625374865048;1064.499685147979;1069.6630561373142;15069;15643;15671.921640391725;15913.68172636564;16031.438899638457;413953;650206;659763;660873;666401;53234.20148697773;53542.46457535375;53615.73192134092;53821.1926821098;53830.461695383325;1150.9654710358702;1192.4693511387522;1260.602910083321;1293.6313150752178;1308.30005946818;29555.261118472212;32458.35083298368;32543.674563255012;32627.67372326341;33097.83451082729;49351.272705455136;50178.49821501738;50325.74837125791;50356.24465632945;50405;361180;369594;369844;369864;369931;23288.4177895554;25546.361340965883;25599;26256;26380.73619263783;43541;43612;44132.558674412845;44259.55740442554;44757.55242447664;37371.439428408055;37867.431988520744;38178;38775.06124693697;39067.6093239064;37580.24839503249;37769.62230377771;37912.62087379091;38253;38364.424533631456;37372.439413408276;37543;37705.43441848319;40797.59202407938;43068.56931430646;36616.81691591525;40366.79816600898;40991.180176396905;41122.383164251965;41310.173796524505;30645.03347993192;31399.372012559164;31806.36387272288;32088.83955580207;32428.513572296906 16:01:45 INFO - PROCESS | 1552 | |2;jquery.html;372.2201986837574;375.63750980304;379.84046700385835;383.8925100971729;389.1497078882642;16072.598185045365;17487.65024699507;17553.64892702145;17838.732419013708;17943.82056179438;14868.47960321389;15402.075875447481;15418.922905385458;15505;16123.193840307968;4702.083093796711;4832.613390928735;4868.391451068616;4869.391326084239;4870.0746858096845;3513.3149035938004;4857.174280372327;5331.466853314657;5614.410486898878;5628.296462942132;5669.546436285108;5688.658680479184;5703.9714801425935;5708.1437784332265;5716.8856622867515;5479.4520547945285;5517.44825517447;5625.943740562583;5697.6866272355;5700.6579605223615;3388.7797293176113;5134.17853143499;5397.649152805095;5437.401885792559;5449.618526703132;11837.763244735143;12383;12706.110572259946;12728.109032367738;12796.104272700915;2006.047127614386;2093.225506562582;2119.6078725435846;2279.338991692407;2281.4296425893526;9088.772780680536;9097.499637519933;9398.530073496298;9686.806263874683;9834.114929656294;1765.6203916158088;1787.928482860684;1790.4628611416522;1822.1071674879347;1826.9451916442529;64919.67540162269;65354.67322663404;67523.32476675269;67635;68344.3165568343;10352.689419317432;10355.326903751233;10435.16518678504;10458.68623941283;10596.258261921746;14280.642983925485;14377.353019114165;14382.496612618508;14386.928065359816;14411.207383594003;6912.619805910723;7116.857662846714;7224.67488962998;7246.384057355184;7260.056192694916;6253.874922501524;6289.276733175743;6349.55553111287;6631.668416579152;6726.730930762813;4436.290193569013;4497.662675299301;4502.954970450319;4529.207388706963;4621.768911554476;1759.102857542637;1763.2946821271594;1851.5093500222451;1864.3474783825823;1906.637738829618;799.312591171592;882.6337070115958;888.8888888888889;895.7178488776015;905.316486053039;16061.598460038593;16634.25145868439;17001.48995530136;17155;17599.120043997747;783.1385475976494;886.8625363068735;892.5760263874607;904.2901322461836;926.1433174313732;9458.85811712825;9554.856677149848;9796.216302695768;9863.704088877344;9910.752231194134;347243;357960;358741;359577;362057;68934;75712;76419.23580764233;76820;78005;915.2494954137543;920.5029284186598;942.9669961551313;943.429225318686;951.9048095190564;15006.549803505912;15103.697926041197;15239.31423085986;15329.846701532842;15417.84582154209;33965.66034339625;34032;34452.48321275133;35021.522666214274;35047.47428788621;49265.50734492609;49447.75276123596;49481.752591236815;49560;49797.253041205135;6975.128108986377;7164.104486939133;7721.112072111554;7829.6085195741125;8065.677372905018;10193;11152.888471115184;11772.70568235801;12454;12488.188267762567;10522.421266830397;10554.630587929692;10608.840867387149;10946.17903657213;11075.833862491907;10512.684619461428;10699.411532365795;10903.945480272549;11150.665480035612;11284.7743045137;10431.739206519898;10812.891871081189;11083.556657733923;11085.22403431752;11190.552377905118;7478.140013898471;7570.205128461433;7582.924170758442;7583.469157158946;7642.847143056996;10457.215708821717;10745.24783265164;10839;10869.782604348027;11045.28205666629 16:01:45 INFO - PROCESS | 1552 | |3;mootools.html;1269.3843485909338;1451.4919778077674;1489.3000289863764;1582.1930815284202;1782.2692695994645;6539.967300163498;7284.672189751461;7462.850742985137;7464.8507029859375;7726.497777644449;7454.403647708184;7611.619419029054;7617.2001939796355;7628.084629844412;7656.425768067389;2226.565819665162;2253.7070180876544;2258.6612008198736;2259.051198496635;2260.2202240227093;2178.302943058222;2186.278528085736;2227.8329125315586;2233.3858188997992;2234.9217777377794;2193.616117179487;2206.646936490162;2221.600111979843;2224.232639739287;2224.3994121587243;2164.9025793839314;2180.8800515971693;2187.660912558556;2188.912443502274;2191.2221161487682;2158.9136434542597;2169.262450766747;2172.1420039084474;2175.695402643631;2178.9891050544807;7496.512726672749;7514.211007844152;7607.923920760777;7617.314441700219;7720.884186737204;4505.504394516601;4576.427946506687;4588.220002599566;4620.676552641316;4622.422197225347;2906.9273268168254;2985.970140298591;2991.2820922978544;3006;3013.2617508710505;914.3553794574809;927.7958849053198;928.767808047988;930.972070837876;934.2712684106408;10301.53643086063;10364.222683298707;10379.11777498906;10398.740031499197;10412;4027.5368332641533;4118.876433706993;4190.036291652937;4191.811368488426;4206.831726730958;8282.088970213272;8310.168983101641;8315.126911674308;8320.126386729427;8335.166483351737;4384.232759267115;4425.203463376623;4433.534478879671;4433.6009759121835;4434.711743736647;3775.244951009809;3967.0677390813134;4065.898352541151;4101.364288535282;4186.434831297735;3469.3755124077907;3471.0801637566065;3535.416656251741;3569.3218288525095;3581.4806853006166;1027.2244455436098;1039.8509646840284;1041.271110222847;1049.1921220660201;1053.3048188195908;858.4720396955827;879.8064425826308;887.2857349833317;899.9280057595377;900.0774206438452;5302.522772950453;5390.676559406448;5403.4326395727885;5606.775728970877;5657.20799088128;843.6667516331015;884.6947803007952;891.8974317953389;931.6366617019368;931.8322701913721;3757.812109394519;3818.3508803502914;3871.109644781716;3939.2121575684405;3954.683625309968;9212.309076819265;9322.627094916128;9420.717378478657;9435.05649435051;9449.952750236205;9286.303527235485;9302;9320.90679093214;9330.346875718635;9352.298577606707;6829.556078854958;6871.381575658214;7118.359347658736;7154.678039488235;7330.96334518324;5360.865978350573;5531.446855314436;5608.495235428831;5688.971555142198;5782;8699.739007829776;8746.65013399457;8971.102889710977;8976.28189744832;8982.461052336881;8828.337874659297;8961.23829474502;9019.278457723236;9040.63837446521;9041.276697864287;6024;6510.934890651222;6699.76550820719;6718.630475323708;6852.486063545353;7292.781216563511;7305;7328.523645962995;7358.668859901424;7367.668454919434;6717.697703603252;6812.62530560804;6886.380225779704;6932.994715766286;7030.367266945999;6573.408393244631;6586.275509693834;6612.0743095965645;6636.668166591748;6664.8000559983275;6033.698315084316;6064.787732429344;6151.2618485781995;6158.3841615838055;6166.660833654192;4360.847370342023;4439.5338489458145;4494.752788596527;4498.572635599613;4629.560191781776;4684.063187362609;4686;4690.718556886462;4725.054989002145;4737.431508219036 16:01:45 INFO - PROCESS | 1552 | |4;prototype.html;289.2522828488359;304.68160771993263;305.96022517072777;309.43682497853905;315.7379375118652;4817.927731084036;4844.563989240967;4860.58685011774;4873.878153046176;4899.314096026555;4535.455745310567;4612.538746125386;4665.603423708989;4753.16819556578;4770.189067858464;3804.90487737806;3817.5800661927165;3842.2507611015862;3850.8844734657864;3861.3049651062797;3785.71607129465;3803.220339830342;3820.808959552025;3821.3503704370323;3832.9808350958206;3607.188382613904;3635.709143268545;3758.906027349311;3776.7922764247955;3794.8482060717674;3735.402335626287;3739.6447337502896;3757.3424650686293;3764.0589852536864;3768.3593789055926;3330.150811543065;3352.7820691654965;3358.0429577570276;3359.9160020999425;3363.9159021024425;4449.755263460508;4617.907641847144;4625.306204069383;4630.606398456135;4655.301704744315;4262.211490874198;4302.569743025703;4307.913841723148;4311.094670119279;4319.546447623017;4951.282064100721;4974.4579180454175;4975.228839529878;4975.676581022222;4981.526754958273;3658.32321020612;3667.3948798448223;3674.6692797648075;3675.4119340905595;3681.9263614727556;5022.447530771612;5209.635325527214;5228.503292187236;5228.529432351069;5234.0840352938085;3713.2759111972905;3737.5141231639714;3742.4947632069866;3745.5392396965203;3750.643688849555;3780.1683629601443;3813.4851795007876;3823.598522155189;3824.541055073409;3833.674137698271;4063.309237429644;4282.614564689131;4305.870823875289;4325.416068830667;4341.131773645284;4990.6257030722845;5037.596992240612;5044.243363495505;5045.596352291808;5061.924071138936;1214.611324376191;1246.6571692784376;1266.6136828267283;1275.993620031894;1286.7426514697097;820.9368867316839;829.742779738283;835.9665613375519;838.9748307550783;843.2832092721261;4013.8595149170224;4060.127072679388;4060.898477538026;4086.3053280942313;4098.364753463218;4572.702774319659;4609.879455450463;4641.303804429295;4668.743219122913;4696.412948381452;4067.898302542401;4082.795860206978;4087.4481944937647;4094.4063110849356;4101.1387608601935;2418.6734790803366;2510.0712736287323;2517.420993171544;2524.5329614021466;2525.724509122918;4447.399601053881;4467.530909254547;4489.528599497071;4495.865124046284;4504.662150338672;4765.761711914391;4789.688670236424;4799.87203007293;4805.207140821795;4828.565429111397;4430.446194225722;4496.4154659894;4622.583967442946;5023.949760502494;5035.496450354935;5186.962607478444;5222.138347172826;5228.477152284741;5250.894982100413;5283.498067683564;5094.2103973884105;5124.333836601367;5153.896922061463;5170.40540337866;5201.739913004259;4762.356891924876;4967.40391153064;4990.451050384528;4991.9500804991485;5030.421501527371;3612.9096772580897;3618.1497348122643;3632.9636703632627;3642.726795490296;3645.1251699592435;5042.268871013609;5282.652923504482;5288.7355632219005;5302.681839089667;5312.123499622674;4934.950650493593;4994.326900488377;5019.146745053276;5023.623228257822;5030.899382012412;5008.974955125201;5010.248462730474;5018.4730603286125;5036.093503169464;5040.218766091261;4818.662693611554;4945.159322915041;4961.677490963076;4963.255511673278;4970.676906001098;5021.949780502148;5036.521530454601;5043.697378157175;5055.393352797688;5063.367079115111;4947.925781113214;4954.42369967622;4968.180250258667;4973.378327709036;4987.326710894128 16:01:45 INFO - PROCESS | 1552 | |5;yui.html;681.5399605266446;706.4630880530796;712.6899798587613;715.2632788228124;718.1095441652353;11560.884391156096;12168.756624867498;12183.512659493632;12185.207961482498;12185.451654675539;11743.06055515559;11821.881781182206;11836.349000804952;11881.168318217728;11889.227200231979;847.3729440214244;861.0485413617956;862.8188080503102;863.1196179896501;863.6847550644001;674.0899785289869;837.6774941647449;859.1408591408591;859.2009431228986;872.9039805621409;893.0845882969975;894.5661354243186;897.3045889435674;897.3090720145484;897.9506127163004;710.4671496377716;897.5646811296516;898.1871406377173;898.4519443139679;898.8292224477328;642.2613993906998;659.0213532903634;661.0150875195972;662.3972185311392;664.7706541243264;12212.145149839425;12254.81617775734;12268.263904165779;12280.877191228063;12286.324252166127;10825.512851921603;11093.223474356799;11879.227850189796;12615.180013299107;12746.36268186587;10685.893141068567;11044.613438529614;11234.269772464846;11257;11333;3927.0182454386404;3940.704447166474;3983.40248962655;4072.429859819628;4084.1627466369323;45838;46288.53711462876;47341;47878.760606197095;48508.5149148504;9222.631094756134;9277.304202184863;9314.487703176255;9505.477198754135;9614.95192524047;16858.662826743643;16899.66200676004;16905.7464138038;16931.492055238115;17619.647607047787;6639.136912201383;6690.029945657852;6707.262201157869;6801.251862295144;6877.243503214642;3775.169462718198;3832.789196594214;3840.4047372657283;3901.96098039016;3928.1750832325533;2772.07135609569;2845.558938364557;2855.628768260113;2855.9857200714277;2860.7568356689912;514.477805027897;522.2297111760199;525.3118414876524;525.5454032262065;526.210683974039;511.8157463313279;535.2070563611946;538.1765898175797;541.6181591977646;544.7766415769515;6754.5609535380045;6858.897116543256;6867.107276054081;6928.653567321614;7023.578585284899;493.3832709113608;573.9339975902825;585.6017907822721;592.5764710537791;594.8364199845078;3436.43298855686;3502.8949131526097;3560.038789526813;3613.512175856226;3669.9082522936606;50199.74900125476;54471.45528544664;55866.72066639722;55987.720061399436;56283.71858140683;50664;51481;52000;52669.47330526722;53464.19803702948;46190;46851.29723054157;47204.7639761799;47698.5230147701;47709.28436073531;6935.410490108397;7117.8932316016335;7146.106736657918;7230.493865429369;7394.371478424394;31296.374072518876;31671.20821979469;31744.841275793475;32715.509267361485;32936.67063329336;37139.628603713616;41385.79307103445;41604;41973.370399443425;42038.78980605077;9997.250206234416;10400.635977740743;10596.993285637853;10709.410982396043;10811.783764324828;11474.655760327203;11602.535898563963;11794.882051179378;11846.881531184577;12438.626841194779;10385.740356491147;10504.632337868137;10632.627858024933;10870.891291086988;10916.890831091587;11116.777664446827;11147.83278250843;11216.046636036028;11680.94159529197;11856.34790086519;10419.166466682798;10532.315399499008;10712.303700259457;10975.243960966207;10998.780024399308;9323.440593564408;9426.104520070583;9503.382280151767;9870.901290987285;9922.503874806087;8539;8695.304375649806;8712.12878712149;8721.956390218009;8918 16:01:45 INFO - PROCESS | 1552 | __end_tp_report 16:01:45 INFO - PROCESS | 1552 | __start_cc_report 16:01:45 INFO - PROCESS | 1552 | _x_x_mozilla_cycle_collect,995 16:01:45 INFO - PROCESS | 1552 | __end_cc_report 16:01:45 INFO - PROCESS | 1552 | __startTimestamp1463526105957__endTimestamp 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | ------- Summary: start ------- 16:01:45 INFO - PROCESS | 1552 | Number of tests: 6 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#0] dojo.html Cycles:165 Average:4745.85 Median:4891.41 stddev:496.92 (10.2%) stddev-sans-first:470.92 16:01:45 INFO - PROCESS | 1552 | Values: 2666.7 2683.0 2716.3 2718.2 2728.7 4647.4 4707.6 4712.3 4745.8 4817.1 4744.5 4756.6 4784.5 4806.5 4808.3 4401.7 4416.6 4449.3 4466.9 4475.8 4392.4 4482.6 4484.8 4515.8 4559.0 4496.9 4558.0 4583.0 4583.4 4593.3 4273.4 4326.1 4376.0 4388.9 4456.6 4340.0 4340.8 4354.2 4391.1 4404.3 4741.4 4748.0 4748.2 4760.1 4763.7 4983.4 5032.5 5113.8 5116.8 5171.0 5137.4 5203.2 5206.9 5209.2 5210.9 4815.5 4833.1 4860.8 4868.3 4872.6 5057.6 5183.1 5262.0 5262.3 5296.3 5030.1 5067.9 5094.6 5126.3 5127.5 4934.6 5102.0 5118.9 5125.8 5129.7 5088.9 5090.2 5112.9 5156.8 5157.8 5086.7 5086.8 5089.2 5093.5 5138.4 4198.6 4247.1 4297.2 4318.8 4354.2 3701.5 3750.6 3750.7 3774.5 3783.6 4388.2 4407.2 4413.4 4414.4 4459.2 5010.8 5086.2 5113.3 5122.2 5175.9 4386.4 4420.2 4430.8 4465.1 4473.1 4637.3 4646.8 4655.3 4660.5 4704.6 4706.4 4820.2 4886.4 4891.4 4893.4 5145.2 5165.0 5177.7 5233.8 5234.4 4880.7 4893.7 4897.8 4901.3 4947.5 4900.5 5034.4 5062.0 5100.7 5111.8 5135.4 5144.6 5166.9 5184.1 5232.7 4987.8 4991.5 5029.7 5031.5 5057.0 4819.4 4924.8 4955.5 5019.1 5031.7 4934.3 4949.8 4973.9 4978.5 4986.2 5049.2 5053.6 5058.2 5060.2 5094.0 4811.9 4871.6 4912.0 4926.1 4990.5 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#1] ext.html Cycles:180 Average:55486.26 Median:39540.40 stddev:117100.72 (296.2%) stddev-sans-first:117356.59 16:01:45 INFO - PROCESS | 1552 | Values: 553.0 591.8 600.7 604.7 605.1 28400.3 41851.0 42454.8 42584.1 42670.4 43652.3 44181.8 44274.8 44301.1 44354.3 8005.7 8162.6 8170.9 8176.3 8178.6 8001.7 8083.3 8089.7 8090.2 8091.5 8065.8 8097.2 8117.3 8122.3 8135.1 8031.1 8058.7 8080.9 8096.5 8110.6 7962.4 8038.8 8055.0 8056.5 8059.9 40013.2 40303.6 40410.2 40418.4 40428.2 3077.5 3127.8 3141.7 3226.2 3349.9 30293.4 30640.7 31215.1 31463.0 32427.0 3463.4 3502.0 3509.2 3515.2 3653.3 189198.0 189973.0 260194.0 261442.0 261484.0 52125.5 52371.0 52623.0 52658.2 52755.2 34818.7 35488.5 35528.3 37727.8 61660.7 12061.4 12091.3 12099.8 12103.0 12123.3 9888.2 9994.5 9998.0 10004.8 10010.4 19652.9 19749.6 19761.6 19798.2 19804.3 7250.5 7274.5 7339.9 7369.6 7403.1 813.7 926.2 986.0 1007.2 1025.4 28785.0 29598.6 29618.9 29828.7 30091.8 987.2 995.4 1040.6 1064.5 1069.7 15069.0 15643.0 15671.9 15913.7 16031.4 413953.0 650206.0 659763.0 660873.0 666401.0 53234.2 53542.5 53615.7 53821.2 53830.5 1151.0 1192.5 1260.6 1293.6 1308.3 29555.3 32458.4 32543.7 32627.7 33097.8 49351.3 50178.5 50325.7 50356.2 50405.0 361180.0 369594.0 369844.0 369864.0 369931.0 23288.4 25546.4 25599.0 26256.0 26380.7 43541.0 43612.0 44132.6 44259.6 44757.6 37371.4 37867.4 38178.0 38775.1 39067.6 37580.2 37769.6 37912.6 38253.0 38364.4 37372.4 37543.0 37705.4 40797.6 43068.6 36616.8 40366.8 40991.2 41122.4 41310.2 30645.0 31399.4 31806.4 32088.8 32428.5 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#2] jquery.html Cycles:180 Average:22988.95 Median:386.52 stddev:59088.42 (15287.2%) stddev-sans-first:59229.78 16:01:45 INFO - PROCESS | 1552 | Values: 372.2 375.6 379.8 383.9 389.1 16072.6 17487.7 17553.6 17838.7 17943.8 14868.5 15402.1 15418.9 15505.0 16123.2 4702.1 4832.6 4868.4 4869.4 4870.1 3513.3 4857.2 5331.5 5614.4 5628.3 5669.5 5688.7 5704.0 5708.1 5716.9 5479.5 5517.4 5625.9 5697.7 5700.7 3388.8 5134.2 5397.6 5437.4 5449.6 11837.8 12383.0 12706.1 12728.1 12796.1 2006.0 2093.2 2119.6 2279.3 2281.4 9088.8 9097.5 9398.5 9686.8 9834.1 1765.6 1787.9 1790.5 1822.1 1826.9 64919.7 65354.7 67523.3 67635.0 68344.3 10352.7 10355.3 10435.2 10458.7 10596.3 14280.6 14377.4 14382.5 14386.9 14411.2 6912.6 7116.9 7224.7 7246.4 7260.1 6253.9 6289.3 6349.6 6631.7 6726.7 4436.3 4497.7 4503.0 4529.2 4621.8 1759.1 1763.3 1851.5 1864.3 1906.6 799.3 882.6 888.9 895.7 905.3 16061.6 16634.3 17001.5 17155.0 17599.1 783.1 886.9 892.6 904.3 926.1 9458.9 9554.9 9796.2 9863.7 9910.8 347243.0 357960.0 358741.0 359577.0 362057.0 68934.0 75712.0 76419.2 76820.0 78005.0 915.2 920.5 943.0 943.4 951.9 15006.5 15103.7 15239.3 15329.8 15417.8 33965.7 34032.0 34452.5 35021.5 35047.5 49265.5 49447.8 49481.8 49560.0 49797.3 6975.1 7164.1 7721.1 7829.6 8065.7 10193.0 11152.9 11772.7 12454.0 12488.2 10522.4 10554.6 10608.8 10946.2 11075.8 10512.7 10699.4 10903.9 11150.7 11284.8 10431.7 10812.9 11083.6 11085.2 11190.6 7478.1 7570.2 7582.9 7583.5 7642.8 10457.2 10745.2 10839.0 10869.8 11045.3 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#3] mootools.html Cycles:180 Average:5065.60 Median:5607.64 stddev:2770.28 (49.4%) stddev-sans-first:2763.36 16:01:45 INFO - PROCESS | 1552 | Values: 1269.4 1451.5 1489.3 1582.2 1782.3 6540.0 7284.7 7462.9 7464.9 7726.5 7454.4 7611.6 7617.2 7628.1 7656.4 2226.6 2253.7 2258.7 2259.1 2260.2 2178.3 2186.3 2227.8 2233.4 2234.9 2193.6 2206.6 2221.6 2224.2 2224.4 2164.9 2180.9 2187.7 2188.9 2191.2 2158.9 2169.3 2172.1 2175.7 2179.0 7496.5 7514.2 7607.9 7617.3 7720.9 4505.5 4576.4 4588.2 4620.7 4622.4 2906.9 2986.0 2991.3 3006.0 3013.3 914.4 927.8 928.8 931.0 934.3 10301.5 10364.2 10379.1 10398.7 10412.0 4027.5 4118.9 4190.0 4191.8 4206.8 8282.1 8310.2 8315.1 8320.1 8335.2 4384.2 4425.2 4433.5 4433.6 4434.7 3775.2 3967.1 4065.9 4101.4 4186.4 3469.4 3471.1 3535.4 3569.3 3581.5 1027.2 1039.9 1041.3 1049.2 1053.3 858.5 879.8 887.3 899.9 900.1 5302.5 5390.7 5403.4 5606.8 5657.2 843.7 884.7 891.9 931.6 931.8 3757.8 3818.4 3871.1 3939.2 3954.7 9212.3 9322.6 9420.7 9435.1 9450.0 9286.3 9302.0 9320.9 9330.3 9352.3 6829.6 6871.4 7118.4 7154.7 7331.0 5360.9 5531.4 5608.5 5689.0 5782.0 8699.7 8746.7 8971.1 8976.3 8982.5 8828.3 8961.2 9019.3 9040.6 9041.3 6024.0 6510.9 6699.8 6718.6 6852.5 7292.8 7305.0 7328.5 7358.7 7367.7 6717.7 6812.6 6886.4 6933.0 7030.4 6573.4 6586.3 6612.1 6636.7 6664.8 6033.7 6064.8 6151.3 6158.4 6166.7 4360.8 4439.5 4494.8 4498.6 4629.6 4684.1 4686.0 4690.7 4725.1 4737.4 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#4] prototype.html Cycles:180 Average:4123.09 Median:4611.21 stddev:1195.60 (25.9%) stddev-sans-first:1163.81 16:01:45 INFO - PROCESS | 1552 | Values: 289.3 304.7 306.0 309.4 315.7 4817.9 4844.6 4860.6 4873.9 4899.3 4535.5 4612.5 4665.6 4753.2 4770.2 3804.9 3817.6 3842.3 3850.9 3861.3 3785.7 3803.2 3820.8 3821.4 3833.0 3607.2 3635.7 3758.9 3776.8 3794.8 3735.4 3739.6 3757.3 3764.1 3768.4 3330.2 3352.8 3358.0 3359.9 3363.9 4449.8 4617.9 4625.3 4630.6 4655.3 4262.2 4302.6 4307.9 4311.1 4319.5 4951.3 4974.5 4975.2 4975.7 4981.5 3658.3 3667.4 3674.7 3675.4 3681.9 5022.4 5209.6 5228.5 5228.5 5234.1 3713.3 3737.5 3742.5 3745.5 3750.6 3780.2 3813.5 3823.6 3824.5 3833.7 4063.3 4282.6 4305.9 4325.4 4341.1 4990.6 5037.6 5044.2 5045.6 5061.9 1214.6 1246.7 1266.6 1276.0 1286.7 820.9 829.7 836.0 839.0 843.3 4013.9 4060.1 4060.9 4086.3 4098.4 4572.7 4609.9 4641.3 4668.7 4696.4 4067.9 4082.8 4087.4 4094.4 4101.1 2418.7 2510.1 2517.4 2524.5 2525.7 4447.4 4467.5 4489.5 4495.9 4504.7 4765.8 4789.7 4799.9 4805.2 4828.6 4430.4 4496.4 4622.6 5023.9 5035.5 5187.0 5222.1 5228.5 5250.9 5283.5 5094.2 5124.3 5153.9 5170.4 5201.7 4762.4 4967.4 4990.5 4992.0 5030.4 3612.9 3618.1 3633.0 3642.7 3645.1 5042.3 5282.7 5288.7 5302.7 5312.1 4935.0 4994.3 5019.1 5023.6 5030.9 5009.0 5010.2 5018.5 5036.1 5040.2 4818.7 4945.2 4961.7 4963.3 4970.7 5021.9 5036.5 5043.7 5055.4 5063.4 4947.9 4954.4 4968.2 4973.4 4987.3 16:01:45 INFO - PROCESS | 1552 | 16:01:45 INFO - PROCESS | 1552 | [#5] yui.html Cycles:180 Average:13174.24 Median:47703.90 stddev:15543.25 (32.6%) stddev-sans-first:15558.54 16:01:45 INFO - PROCESS | 1552 | Values: 681.5 706.5 712.7 715.3 718.1 11560.9 12168.8 12183.5 12185.2 12185.5 11743.1 11821.9 11836.3 11881.2 11889.2 847.4 861.0 862.8 863.1 863.7 674.1 837.7 859.1 859.2 872.9 893.1 894.6 897.3 897.3 898.0 710.5 897.6 898.2 898.5 898.8 642.3 659.0 661.0 662.4 664.8 12212.1 12254.8 12268.3 12280.9 12286.3 10825.5 11093.2 11879.2 12615.2 12746.4 10685.9 11044.6 11234.3 11257.0 11333.0 3927.0 3940.7 3983.4 4072.4 4084.2 45838.0 46288.5 47341.0 47878.8 48508.5 9222.6 9277.3 9314.5 9505.5 9615.0 16858.7 16899.7 16905.7 16931.5 17619.6 6639.1 6690.0 6707.3 6801.3 6877.2 3775.2 3832.8 3840.4 3902.0 3928.2 2772.1 2845.6 2855.6 2856.0 2860.8 514.5 522.2 525.3 525.5 526.2 511.8 535.2 538.2 541.6 544.8 6754.6 6858.9 6867.1 6928.7 7023.6 493.4 573.9 585.6 592.6 594.8 3436.4 3502.9 3560.0 3613.5 3669.9 50199.7 54471.5 55866.7 55987.7 56283.7 50664.0 51481.0 52000.0 52669.5 53464.2 46190.0 46851.3 47204.8 47698.5 47709.3 6935.4 7117.9 7146.1 7230.5 7394.4 31296.4 31671.2 31744.8 32715.5 32936.7 37139.6 41385.8 41604.0 41973.4 42038.8 9997.3 10400.6 10597.0 10709.4 10811.8 11474.7 11602.5 11794.9 11846.9 12438.6 10385.7 10504.6 10632.6 10870.9 10916.9 11116.8 11147.8 11216.0 11680.9 11856.3 10419.2 10532.3 10712.3 10975.2 10998.8 9323.4 9426.1 9503.4 9870.9 9922.5 8539.0 8695.3 8712.1 8722.0 8918.0 16:01:45 INFO - PROCESS | 1552 | -------- Summary: end -------- 16:01:45 INFO - PROCESS | 1552 | 16:01:46 INFO - TEST-INFO | 1552: exit 0 16:01:46 INFO - TEST-OK | dromaeo_css | took 1119580ms 16:01:46 INFO - TEST-START | kraken 16:01:46 INFO - operating with platform_type : linux_ 16:01:46 INFO - Initialising browser for kraken test... 16:01:46 INFO - TEST-INFO | started process 1733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwiLGz1/profile http://localhost:51874/getInfo.html) 16:01:51 INFO - PROCESS | 1733 | __metrics Screen width/height:1600/1200 16:01:51 INFO - PROCESS | 1733 | colorDepth:24 16:01:51 INFO - PROCESS | 1733 | Browser inner width/height: 1024/697 16:01:51 INFO - PROCESS | 1733 | __metrics 16:01:52 ERROR - PROCESS | 1733 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:01:53 INFO - TEST-INFO | 1733: exit 0 16:01:53 INFO - Browser initialized. 16:01:53 INFO - Running cycle 1/1 for kraken test... 16:01:53 INFO - TEST-INFO | started process 1814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwiLGz1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:05 INFO - PROCESS | 1814 | RSS: Main: 154525696 16:02:05 INFO - PROCESS | 1814 | 16:02:07 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51874/tests/kraken/driver.html?testName=audio-beat-detection) 16:02:08 INFO - PROCESS | 1814 | RSS: Main: 210198528 16:02:08 INFO - PROCESS | 1814 | 16:02:11 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51874/tests/kraken/driver.html?testName=audio-dft) 16:02:12 INFO - PROCESS | 1814 | RSS: Main: 198144000 16:02:12 INFO - PROCESS | 1814 | 16:02:15 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51874/tests/kraken/driver.html?testName=audio-fft) 16:02:16 INFO - PROCESS | 1814 | RSS: Main: 267313152 16:02:16 INFO - PROCESS | 1814 | 16:02:18 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51874/tests/kraken/driver.html?testName=audio-oscillator) 16:02:19 INFO - PROCESS | 1814 | RSS: Main: 176168960 16:02:19 INFO - PROCESS | 1814 | 16:02:21 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51874/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:02:22 INFO - PROCESS | 1814 | RSS: Main: 180211712 16:02:22 INFO - PROCESS | 1814 | 16:02:25 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51874/tests/kraken/driver.html?testName=imaging-darkroom) 16:02:25 INFO - PROCESS | 1814 | RSS: Main: 216227840 16:02:25 INFO - PROCESS | 1814 | 16:02:29 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51874/tests/kraken/driver.html?testName=imaging-desaturate) 16:02:30 INFO - PROCESS | 1814 | RSS: Main: 214724608 16:02:30 INFO - PROCESS | 1814 | 16:02:33 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51874/tests/kraken/driver.html?testName=json-parse-financial) 16:02:33 INFO - PROCESS | 1814 | RSS: Main: 215449600 16:02:33 INFO - PROCESS | 1814 | 16:02:35 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51874/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:02:36 INFO - PROCESS | 1814 | RSS: Main: 205180928 16:02:36 INFO - PROCESS | 1814 | 16:02:38 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:02:38 INFO - PROCESS | 1814 | RSS: Main: 207970304 16:02:38 INFO - PROCESS | 1814 | 16:02:41 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:02:41 INFO - PROCESS | 1814 | RSS: Main: 218304512 16:02:41 INFO - PROCESS | 1814 | 16:02:44 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:02:45 INFO - PROCESS | 1814 | RSS: Main: 220868608 16:02:45 INFO - PROCESS | 1814 | 16:02:48 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:02:49 INFO - PROCESS | 1814 | RSS: Main: 220397568 16:02:49 INFO - PROCESS | 1814 | 16:02:50 INFO - PROCESS | 1814 | Cycle 1(1): loaded http://localhost:51874/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51874/tests/kraken/driver.html?testName=ai-astar) 16:02:51 INFO - PROCESS | 1814 | RSS: Main: 267206656 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | __start_tp_report 16:02:51 INFO - PROCESS | 1814 | _x_x_mozilla_page_load 16:02:51 INFO - PROCESS | 1814 | _x_x_mozilla_page_load_details 16:02:51 INFO - PROCESS | 1814 | |i|pagename|runs| 16:02:51 INFO - PROCESS | 1814 | |0;ai-astar;112;109;111;111;111;112;114;112;112;111 16:02:51 INFO - PROCESS | 1814 | |1;audio-beat-detection;161;130;159;213;134;155;156;130;198;133 16:02:51 INFO - PROCESS | 1814 | |2;audio-dft;163;162;161;162;164;160;162;160;161;159 16:02:51 INFO - PROCESS | 1814 | |3;audio-fft;76;75;77;152;77;75;76;74;126;74 16:02:51 INFO - PROCESS | 1814 | |4;audio-oscillator;92;93;150;94;94;93;129;92;92;118 16:02:51 INFO - PROCESS | 1814 | |5;imaging-gaussian-blur;116;117;117;116;117;117;116;117;117;117 16:02:51 INFO - PROCESS | 1814 | |6;imaging-darkroom;149;149;149;149;149;148;149;148;148;146 16:02:51 INFO - PROCESS | 1814 | |7;imaging-desaturate;95;94;95;95;95;94;95;95;95;95 16:02:51 INFO - PROCESS | 1814 | |8;json-parse-financial;66;68;67;67;66;66;66;124;64;64 16:02:51 INFO - PROCESS | 1814 | |9;json-stringify-tinderbox;64;64;64;63;64;63;64;64;71;70 16:02:51 INFO - PROCESS | 1814 | |10;stanford-crypto-aes;82;81;88;85;87;83;83;89;83;88 16:02:51 INFO - PROCESS | 1814 | |11;stanford-crypto-ccm;133;134;136;129;127;129;128;131;277;150 16:02:51 INFO - PROCESS | 1814 | |12;stanford-crypto-pbkdf2;178;165;166;165;166;167;166;166;169;163 16:02:51 INFO - PROCESS | 1814 | |13;stanford-crypto-sha256-iterative;66;68;70;70;64;64;64;67;65;65 16:02:51 INFO - PROCESS | 1814 | __end_tp_report 16:02:51 INFO - PROCESS | 1814 | __start_cc_report 16:02:51 INFO - PROCESS | 1814 | _x_x_mozilla_cycle_collect,2103 16:02:51 INFO - PROCESS | 1814 | __end_cc_report 16:02:51 INFO - PROCESS | 1814 | __startTimestamp1463526171055__endTimestamp 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | ------- Summary: start ------- 16:02:51 INFO - PROCESS | 1814 | Number of tests: 14 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.27 (1.1%) stddev-sans-first:1.33 16:02:51 INFO - PROCESS | 1814 | Values: 112.0 109.0 111.0 111.0 111.0 112.0 114.0 112.0 112.0 111.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#1] audio-beat-detection Cycles:10 Average:156.90 Median:157.50 stddev:28.68 (18.2%) stddev-sans-first:30.39 16:02:51 INFO - PROCESS | 1814 | Values: 161.0 130.0 159.0 213.0 134.0 155.0 156.0 130.0 198.0 133.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.51 (0.9%) stddev-sans-first:1.48 16:02:51 INFO - PROCESS | 1814 | Values: 163.0 162.0 161.0 162.0 164.0 160.0 162.0 160.0 161.0 159.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#3] audio-fft Cycles:10 Average:88.20 Median:75.50 stddev:27.49 (36.4%) stddev-sans-first:28.80 16:02:51 INFO - PROCESS | 1814 | Values: 76.0 75.0 77.0 152.0 77.0 75.0 76.0 74.0 126.0 74.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:92.50 stddev:20.56 (22.2%) stddev-sans-first:21.29 16:02:51 INFO - PROCESS | 1814 | Values: 92.0 93.0 150.0 94.0 94.0 93.0 129.0 92.0 92.0 118.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 16:02:51 INFO - PROCESS | 1814 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:149.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 16:02:51 INFO - PROCESS | 1814 | Values: 149.0 149.0 149.0 149.0 149.0 148.0 149.0 148.0 148.0 146.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:02:51 INFO - PROCESS | 1814 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#8] json-parse-financial Cycles:10 Average:71.80 Median:66.00 stddev:18.38 (27.9%) stddev-sans-first:19.38 16:02:51 INFO - PROCESS | 1814 | Values: 66.0 68.0 67.0 67.0 66.0 66.0 66.0 124.0 64.0 64.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:64.00 stddev:2.88 (4.5%) stddev-sans-first:3.03 16:02:51 INFO - PROCESS | 1814 | Values: 64.0 64.0 64.0 63.0 64.0 63.0 64.0 64.0 71.0 70.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:86.00 stddev:2.88 (3.4%) stddev-sans-first:2.86 16:02:51 INFO - PROCESS | 1814 | Values: 82.0 81.0 88.0 85.0 87.0 83.0 83.0 89.0 83.0 88.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:133.50 stddev:46.02 (34.5%) stddev-sans-first:48.51 16:02:51 INFO - PROCESS | 1814 | Values: 133.0 134.0 136.0 129.0 127.0 129.0 128.0 131.0 277.0 150.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:166.00 stddev:4.12 (2.5%) stddev-sans-first:1.62 16:02:51 INFO - PROCESS | 1814 | Values: 178.0 165.0 166.0 165.0 166.0 167.0 166.0 166.0 169.0 163.0 16:02:51 INFO - PROCESS | 1814 | 16:02:51 INFO - PROCESS | 1814 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:2.36 (3.5%) stddev-sans-first:2.50 16:02:51 INFO - PROCESS | 1814 | Values: 66.0 68.0 70.0 70.0 64.0 64.0 64.0 67.0 65.0 65.0 16:02:51 INFO - PROCESS | 1814 | -------- Summary: end -------- 16:02:51 INFO - PROCESS | 1814 | 16:02:52 INFO - TEST-INFO | 1814: exit 0 16:02:52 INFO - TEST-OK | kraken | took 65388ms 16:02:52 INFO - SUITE-END | took 1184s 16:02:52 INFO - Completed test suite (00:19:45) 16:02:52 INFO - javascript benchmark 16:02:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.6799984001923, 2682.959755603665, 2716.3209197700576, 2718.2388931099285, 2728.7271272872717, 4647.395838540985, 4707.552782485667, 4712.269598212274, 4745.762711864401, 4817.132916075105, 4744.501827308135, 4756.619470442365, 4784.49762774908, 4806.495317991613, 4808.278758186265, 4401.66987475939, 4416.624586910117, 4449.2881139017845, 4466.932996005063, 4475.820967161326, 4392.407025051625, 4482.641388688897, 4484.753338566394, 4515.796789144496, 4559.042601053783, 4496.9325460118125, 4557.977210113945, 4582.9770851145695, 4583.427071616048, 4593.28804035375, 4273.401723758676, 4326.09152078064, 4375.978120109411, 4388.934165987513, 4456.5766252205985, 4340.045190058182, 4340.8480703175555, 4354.216241076605, 4391.099824535994, 4404.2512772828695, 4741.431028276595, 4747.976260118678, 4748.19280722278, 4760.071786001731, 4763.666543341968, 4983.377077865267, 5032.521910418504, 5113.846584602468, 5116.769745361467, 5170.9741451292875, 5137.38351397831, 5203.245529398254, 5206.921896171561, 5209.2446595243855, 5210.921836172461, 4815.518448155227, 4833.05755377705, 4860.8298709545, 4868.294097355863, 4872.561469467765, 5057.570106540912, 5183.144781111075, 5261.973690131525, 5262.315898933114, 5296.25852380667, 5030.0694371541385, 5067.923981140286, 5094.592432605456, 5126.333576635088, 5127.461616530285, 4934.55588996992, 5101.974490127525, 5118.8976220476125, 5125.7513681629325, 5129.743512824344, 5088.898222035538, 5090.160123579568, 5112.948870511322, 5156.845294641167, 5157.8452646420665, 5086.745662716923, 5086.821961231339, 5089.211172268304, 5093.516115968977, 5138.3577052868395, 4198.6221240088535, 4247.086876321606, 4297.226499230169, 4318.762468064287, 4354.172707185625, 3701.5373078365205, 3750.624937506282, 3750.7374483785875, 3774.471573979645, 3783.6027217142355, 4388.166248412791, 4407.228734971367, 4413.360062790877, 4414.426124603781, 4459.175052615276, 5010.774515146827, 5086.16078347076, 5113.309703190096, 5122.206058061007, 5175.870603234874, 4386.429764130707, 4420.248557745191, 4430.8449204277695, 4465.129299786576, 4473.060657262012, 4637.3275874997935, 4646.79089440976, 4655.348251244828, 4660.487346391894, 4704.647151463654, 4706.411698537683, 4820.156472617348, 4886.438059623189, 4891.413030436371, 4893.388326459192, 5145.228215767665, 5165.0, 5177.741112944263, 5233.764480598458, 5234.371875374979, 4880.682755620874, 4893.657443978887, 4897.779599917941, 4901.3383193268455, 4947.45577986414, 4900.509949005071, 5034.44621091687, 5061.9746901266735, 5100.719460429712, 5111.846644600668, 5135.409427915837, 5144.562712169358, 5166.948330516797, 5184.144616138295, 5232.738363081907, 4987.750612469435, 4991.460141517557, 5029.723365214948, 5031.547160755549, 5056.974715126401, 4819.445763737215, 4924.803007879645, 4955.45489996093, 5019.121653710513, 5031.698098114125, 4934.309196712535, 4949.82675606352, 4973.9253911190635, 4978.502149785138, 4986.1772807487805, 5049.192129259301, 5053.570446512088, 5058.190689489664, 5060.2156665716875, 5094.0, 4811.879703007453, 4871.585915197106, 4912.042151780369, 4926.088673595396, 4990.50094990498], "value": 4714.221803364532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.9797523569016, 591.8372447576916, 600.7086563016936, 604.7429842317017, 605.1074664869321, 28400.289992750193, 41851.0, 42454.787726061404, 42584.14831703364, 42670.35994460078, 43652.345214821806, 44181.77909110458, 44274.778626106905, 44301.11397772043, 44354.33468497975, 8005.679772809095, 8162.591870406485, 8170.877436838452, 8176.264136227738, 8178.591070446483, 8001.679932802681, 8083.3129184019135, 8089.716859909904, 8090.190980901922, 8091.4740541864885, 8065.798355041112, 8097.2307630774985, 8117.310028647572, 8122.309603683694, 8135.145809689957, 8031.076426210973, 8058.717944871902, 8080.878786818203, 8096.514209147411, 8110.553919534424, 7962.442629015971, 8038.758837234892, 8054.959725201336, 8056.476329038594, 8059.879101813477, 40013.19973600541, 40303.59696403028, 40410.1917961642, 40418.39372409416, 40428.19143617111, 3077.538369244609, 3127.8279694616785, 3141.701538353853, 3226.2095786532213, 3349.949750753741, 30293.394132117453, 30640.693593064007, 31215.063548093593, 31463.0, 32427.0, 3463.3939060664284, 3501.9824900875587, 3509.175343794206, 3515.156362473014, 3653.2510835278704, 189198.0, 189973.0, 260194.0, 261442.0, 261484.0, 52125.47874521282, 52371.0, 52623.0, 52658.21012684813, 52755.20867186995, 34818.65181348154, 35488.46767298492, 35528.289434211685, 37727.81136094302, 61660.69169654123, 12061.396930153458, 12091.274523528617, 12099.818502722466, 12103.0, 12123.333216673167, 9888.159506441889, 9994.500274986221, 9997.950010249902, 10004.849927251096, 10010.449425281533, 19652.855543113033, 19749.60500789976, 19761.604767904562, 19798.20807167697, 19804.306849260494, 7250.492465527468, 7274.49078564506, 7339.88990165148, 7369.557826530318, 7403.111626604842, 813.6623301329881, 926.2080920812742, 985.9950700246453, 1007.1791489935679, 1025.43601019439, 28785.0, 29598.556021659693, 29618.851905740765, 29828.701712983027, 30091.849540752155, 987.1757082835769, 995.4425521707867, 1040.625374865048, 1064.499685147979, 1069.6630561373142, 15069.0, 15643.0, 15671.921640391725, 15913.68172636564, 16031.438899638457, 413953.0, 650206.0, 659763.0, 660873.0, 666401.0, 53234.20148697773, 53542.46457535375, 53615.73192134092, 53821.1926821098, 53830.461695383325, 1150.9654710358702, 1192.4693511387522, 1260.602910083321, 1293.6313150752178, 1308.30005946818, 29555.261118472212, 32458.35083298368, 32543.674563255012, 32627.67372326341, 33097.83451082729, 49351.272705455136, 50178.49821501738, 50325.74837125791, 50356.24465632945, 50405.0, 361180.0, 369594.0, 369844.0, 369864.0, 369931.0, 23288.4177895554, 25546.361340965883, 25599.0, 26256.0, 26380.73619263783, 43541.0, 43612.0, 44132.558674412845, 44259.55740442554, 44757.55242447664, 37371.439428408055, 37867.431988520744, 38178.0, 38775.06124693697, 39067.6093239064, 37580.24839503249, 37769.62230377771, 37912.62087379091, 38253.0, 38364.424533631456, 37372.439413408276, 37543.0, 37705.43441848319, 40797.59202407938, 43068.56931430646, 36616.81691591525, 40366.79816600898, 40991.180176396905, 41122.383164251965, 41310.173796524505, 30645.03347993192, 31399.372012559164, 31806.36387272288, 32088.83955580207, 32428.513572296906], "value": 18472.63960701341, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.2201986837574, 375.63750980304, 379.84046700385835, 383.8925100971729, 389.1497078882642, 16072.598185045365, 17487.65024699507, 17553.64892702145, 17838.732419013708, 17943.82056179438, 14868.47960321389, 15402.075875447481, 15418.922905385458, 15505.0, 16123.193840307968, 4702.083093796711, 4832.613390928735, 4868.391451068616, 4869.391326084239, 4870.0746858096845, 3513.3149035938004, 4857.174280372327, 5331.466853314657, 5614.410486898878, 5628.296462942132, 5669.546436285108, 5688.658680479184, 5703.9714801425935, 5708.1437784332265, 5716.8856622867515, 5479.4520547945285, 5517.44825517447, 5625.943740562583, 5697.6866272355, 5700.6579605223615, 3388.7797293176113, 5134.17853143499, 5397.649152805095, 5437.401885792559, 5449.618526703132, 11837.763244735143, 12383.0, 12706.110572259946, 12728.109032367738, 12796.104272700915, 2006.047127614386, 2093.225506562582, 2119.6078725435846, 2279.338991692407, 2281.4296425893526, 9088.772780680536, 9097.499637519933, 9398.530073496298, 9686.806263874683, 9834.114929656294, 1765.6203916158088, 1787.928482860684, 1790.4628611416522, 1822.1071674879347, 1826.9451916442529, 64919.67540162269, 65354.67322663404, 67523.32476675269, 67635.0, 68344.3165568343, 10352.689419317432, 10355.326903751233, 10435.16518678504, 10458.68623941283, 10596.258261921746, 14280.642983925485, 14377.353019114165, 14382.496612618508, 14386.928065359816, 14411.207383594003, 6912.619805910723, 7116.857662846714, 7224.67488962998, 7246.384057355184, 7260.056192694916, 6253.874922501524, 6289.276733175743, 6349.55553111287, 6631.668416579152, 6726.730930762813, 4436.290193569013, 4497.662675299301, 4502.954970450319, 4529.207388706963, 4621.768911554476, 1759.102857542637, 1763.2946821271594, 1851.5093500222451, 1864.3474783825823, 1906.637738829618, 799.312591171592, 882.6337070115958, 888.8888888888889, 895.7178488776015, 905.316486053039, 16061.598460038593, 16634.25145868439, 17001.48995530136, 17155.0, 17599.120043997747, 783.1385475976494, 886.8625363068735, 892.5760263874607, 904.2901322461836, 926.1433174313732, 9458.85811712825, 9554.856677149848, 9796.216302695768, 9863.704088877344, 9910.752231194134, 347243.0, 357960.0, 358741.0, 359577.0, 362057.0, 68934.0, 75712.0, 76419.23580764233, 76820.0, 78005.0, 915.2494954137543, 920.5029284186598, 942.9669961551313, 943.429225318686, 951.9048095190564, 15006.549803505912, 15103.697926041197, 15239.31423085986, 15329.846701532842, 15417.84582154209, 33965.66034339625, 34032.0, 34452.48321275133, 35021.522666214274, 35047.47428788621, 49265.50734492609, 49447.75276123596, 49481.752591236815, 49560.0, 49797.253041205135, 6975.128108986377, 7164.104486939133, 7721.112072111554, 7829.6085195741125, 8065.677372905018, 10193.0, 11152.888471115184, 11772.70568235801, 12454.0, 12488.188267762567, 10522.421266830397, 10554.630587929692, 10608.840867387149, 10946.17903657213, 11075.833862491907, 10512.684619461428, 10699.411532365795, 10903.945480272549, 11150.665480035612, 11284.7743045137, 10431.739206519898, 10812.891871081189, 11083.556657733923, 11085.22403431752, 11190.552377905118, 7478.140013898471, 7570.205128461433, 7582.924170758442, 7583.469157158946, 7642.847143056996, 10457.215708821717, 10745.24783265164, 10839.0, 10869.782604348027, 11045.28205666629], "value": 8103.428165918431, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.3843485909338, 1451.4919778077674, 1489.3000289863764, 1582.1930815284202, 1782.2692695994645, 6539.967300163498, 7284.672189751461, 7462.850742985137, 7464.8507029859375, 7726.497777644449, 7454.403647708184, 7611.619419029054, 7617.2001939796355, 7628.084629844412, 7656.425768067389, 2226.565819665162, 2253.7070180876544, 2258.6612008198736, 2259.051198496635, 2260.2202240227093, 2178.302943058222, 2186.278528085736, 2227.8329125315586, 2233.3858188997992, 2234.9217777377794, 2193.616117179487, 2206.646936490162, 2221.600111979843, 2224.232639739287, 2224.3994121587243, 2164.9025793839314, 2180.8800515971693, 2187.660912558556, 2188.912443502274, 2191.2221161487682, 2158.9136434542597, 2169.262450766747, 2172.1420039084474, 2175.695402643631, 2178.9891050544807, 7496.512726672749, 7514.211007844152, 7607.923920760777, 7617.314441700219, 7720.884186737204, 4505.504394516601, 4576.427946506687, 4588.220002599566, 4620.676552641316, 4622.422197225347, 2906.9273268168254, 2985.970140298591, 2991.2820922978544, 3006.0, 3013.2617508710505, 914.3553794574809, 927.7958849053198, 928.767808047988, 930.972070837876, 934.2712684106408, 10301.53643086063, 10364.222683298707, 10379.11777498906, 10398.740031499197, 10412.0, 4027.5368332641533, 4118.876433706993, 4190.036291652937, 4191.811368488426, 4206.831726730958, 8282.088970213272, 8310.168983101641, 8315.126911674308, 8320.126386729427, 8335.166483351737, 4384.232759267115, 4425.203463376623, 4433.534478879671, 4433.6009759121835, 4434.711743736647, 3775.244951009809, 3967.0677390813134, 4065.898352541151, 4101.364288535282, 4186.434831297735, 3469.3755124077907, 3471.0801637566065, 3535.416656251741, 3569.3218288525095, 3581.4806853006166, 1027.2244455436098, 1039.8509646840284, 1041.271110222847, 1049.1921220660201, 1053.3048188195908, 858.4720396955827, 879.8064425826308, 887.2857349833317, 899.9280057595377, 900.0774206438452, 5302.522772950453, 5390.676559406448, 5403.4326395727885, 5606.775728970877, 5657.20799088128, 843.6667516331015, 884.6947803007952, 891.8974317953389, 931.6366617019368, 931.8322701913721, 3757.812109394519, 3818.3508803502914, 3871.109644781716, 3939.2121575684405, 3954.683625309968, 9212.309076819265, 9322.627094916128, 9420.717378478657, 9435.05649435051, 9449.952750236205, 9286.303527235485, 9302.0, 9320.90679093214, 9330.346875718635, 9352.298577606707, 6829.556078854958, 6871.381575658214, 7118.359347658736, 7154.678039488235, 7330.96334518324, 5360.865978350573, 5531.446855314436, 5608.495235428831, 5688.971555142198, 5782.0, 8699.739007829776, 8746.65013399457, 8971.102889710977, 8976.28189744832, 8982.461052336881, 8828.337874659297, 8961.23829474502, 9019.278457723236, 9040.63837446521, 9041.276697864287, 6024.0, 6510.934890651222, 6699.76550820719, 6718.630475323708, 6852.486063545353, 7292.781216563511, 7305.0, 7328.523645962995, 7358.668859901424, 7367.668454919434, 6717.697703603252, 6812.62530560804, 6886.380225779704, 6932.994715766286, 7030.367266945999, 6573.408393244631, 6586.275509693834, 6612.0743095965645, 6636.668166591748, 6664.8000559983275, 6033.698315084316, 6064.787732429344, 6151.2618485781995, 6158.3841615838055, 6166.660833654192, 4360.847370342023, 4439.5338489458145, 4494.752788596527, 4498.572635599613, 4629.560191781776, 4684.063187362609, 4686.0, 4690.718556886462, 4725.054989002145, 4737.431508219036], "value": 4112.934161224795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.2522828488359, 304.68160771993263, 305.96022517072777, 309.43682497853905, 315.7379375118652, 4817.927731084036, 4844.563989240967, 4860.58685011774, 4873.878153046176, 4899.314096026555, 4535.455745310567, 4612.538746125386, 4665.603423708989, 4753.16819556578, 4770.189067858464, 3804.90487737806, 3817.5800661927165, 3842.2507611015862, 3850.8844734657864, 3861.3049651062797, 3785.71607129465, 3803.220339830342, 3820.808959552025, 3821.3503704370323, 3832.9808350958206, 3607.188382613904, 3635.709143268545, 3758.906027349311, 3776.7922764247955, 3794.8482060717674, 3735.402335626287, 3739.6447337502896, 3757.3424650686293, 3764.0589852536864, 3768.3593789055926, 3330.150811543065, 3352.7820691654965, 3358.0429577570276, 3359.9160020999425, 3363.9159021024425, 4449.755263460508, 4617.907641847144, 4625.306204069383, 4630.606398456135, 4655.301704744315, 4262.211490874198, 4302.569743025703, 4307.913841723148, 4311.094670119279, 4319.546447623017, 4951.282064100721, 4974.4579180454175, 4975.228839529878, 4975.676581022222, 4981.526754958273, 3658.32321020612, 3667.3948798448223, 3674.6692797648075, 3675.4119340905595, 3681.9263614727556, 5022.447530771612, 5209.635325527214, 5228.503292187236, 5228.529432351069, 5234.0840352938085, 3713.2759111972905, 3737.5141231639714, 3742.4947632069866, 3745.5392396965203, 3750.643688849555, 3780.1683629601443, 3813.4851795007876, 3823.598522155189, 3824.541055073409, 3833.674137698271, 4063.309237429644, 4282.614564689131, 4305.870823875289, 4325.416068830667, 4341.131773645284, 4990.6257030722845, 5037.596992240612, 5044.243363495505, 5045.596352291808, 5061.924071138936, 1214.611324376191, 1246.6571692784376, 1266.6136828267283, 1275.993620031894, 1286.7426514697097, 820.9368867316839, 829.742779738283, 835.9665613375519, 838.9748307550783, 843.2832092721261, 4013.8595149170224, 4060.127072679388, 4060.898477538026, 4086.3053280942313, 4098.364753463218, 4572.702774319659, 4609.879455450463, 4641.303804429295, 4668.743219122913, 4696.412948381452, 4067.898302542401, 4082.795860206978, 4087.4481944937647, 4094.4063110849356, 4101.1387608601935, 2418.6734790803366, 2510.0712736287323, 2517.420993171544, 2524.5329614021466, 2525.724509122918, 4447.399601053881, 4467.530909254547, 4489.528599497071, 4495.865124046284, 4504.662150338672, 4765.761711914391, 4789.688670236424, 4799.87203007293, 4805.207140821795, 4828.565429111397, 4430.446194225722, 4496.4154659894, 4622.583967442946, 5023.949760502494, 5035.496450354935, 5186.962607478444, 5222.138347172826, 5228.477152284741, 5250.894982100413, 5283.498067683564, 5094.2103973884105, 5124.333836601367, 5153.896922061463, 5170.40540337866, 5201.739913004259, 4762.356891924876, 4967.40391153064, 4990.451050384528, 4991.9500804991485, 5030.421501527371, 3612.9096772580897, 3618.1497348122643, 3632.9636703632627, 3642.726795490296, 3645.1251699592435, 5042.268871013609, 5282.652923504482, 5288.7355632219005, 5302.681839089667, 5312.123499622674, 4934.950650493593, 4994.326900488377, 5019.146745053276, 5023.623228257822, 5030.899382012412, 5008.974955125201, 5010.248462730474, 5018.4730603286125, 5036.093503169464, 5040.218766091261, 4818.662693611554, 4945.159322915041, 4961.677490963076, 4963.255511673278, 4970.676906001098, 5021.949780502148, 5036.521530454601, 5043.697378157175, 5055.393352797688, 5063.367079115111, 4947.925781113214, 4954.42369967622, 4968.180250258667, 4973.378327709036, 4987.326710894128], "value": 3744.5782641811534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.5399605266446, 706.4630880530796, 712.6899798587613, 715.2632788228124, 718.1095441652353, 11560.884391156096, 12168.756624867498, 12183.512659493632, 12185.207961482498, 12185.451654675539, 11743.06055515559, 11821.881781182206, 11836.349000804952, 11881.168318217728, 11889.227200231979, 847.3729440214244, 861.0485413617956, 862.8188080503102, 863.1196179896501, 863.6847550644001, 674.0899785289869, 837.6774941647449, 859.1408591408591, 859.2009431228986, 872.9039805621409, 893.0845882969975, 894.5661354243186, 897.3045889435674, 897.3090720145484, 897.9506127163004, 710.4671496377716, 897.5646811296516, 898.1871406377173, 898.4519443139679, 898.8292224477328, 642.2613993906998, 659.0213532903634, 661.0150875195972, 662.3972185311392, 664.7706541243264, 12212.145149839425, 12254.81617775734, 12268.263904165779, 12280.877191228063, 12286.324252166127, 10825.512851921603, 11093.223474356799, 11879.227850189796, 12615.180013299107, 12746.36268186587, 10685.893141068567, 11044.613438529614, 11234.269772464846, 11257.0, 11333.0, 3927.0182454386404, 3940.704447166474, 3983.40248962655, 4072.429859819628, 4084.1627466369323, 45838.0, 46288.53711462876, 47341.0, 47878.760606197095, 48508.5149148504, 9222.631094756134, 9277.304202184863, 9314.487703176255, 9505.477198754135, 9614.95192524047, 16858.662826743643, 16899.66200676004, 16905.7464138038, 16931.492055238115, 17619.647607047787, 6639.136912201383, 6690.029945657852, 6707.262201157869, 6801.251862295144, 6877.243503214642, 3775.169462718198, 3832.789196594214, 3840.4047372657283, 3901.96098039016, 3928.1750832325533, 2772.07135609569, 2845.558938364557, 2855.628768260113, 2855.9857200714277, 2860.7568356689912, 514.477805027897, 522.2297111760199, 525.3118414876524, 525.5454032262065, 526.210683974039, 511.8157463313279, 535.2070563611946, 538.1765898175797, 541.6181591977646, 544.7766415769515, 6754.5609535380045, 6858.897116543256, 6867.107276054081, 6928.653567321614, 7023.578585284899, 493.3832709113608, 573.9339975902825, 585.6017907822721, 592.5764710537791, 594.8364199845078, 3436.43298855686, 3502.8949131526097, 3560.038789526813, 3613.512175856226, 3669.9082522936606, 50199.74900125476, 54471.45528544664, 55866.72066639722, 55987.720061399436, 56283.71858140683, 50664.0, 51481.0, 52000.0, 52669.47330526722, 53464.19803702948, 46190.0, 46851.29723054157, 47204.7639761799, 47698.5230147701, 47709.28436073531, 6935.410490108397, 7117.8932316016335, 7146.106736657918, 7230.493865429369, 7394.371478424394, 31296.374072518876, 31671.20821979469, 31744.841275793475, 32715.509267361485, 32936.67063329336, 37139.628603713616, 41385.79307103445, 41604.0, 41973.370399443425, 42038.78980605077, 9997.250206234416, 10400.635977740743, 10596.993285637853, 10709.410982396043, 10811.783764324828, 11474.655760327203, 11602.535898563963, 11794.882051179378, 11846.881531184577, 12438.626841194779, 10385.740356491147, 10504.632337868137, 10632.627858024933, 10870.891291086988, 10916.890831091587, 11116.777664446827, 11147.83278250843, 11216.046636036028, 11680.94159529197, 11856.34790086519, 10419.166466682798, 10532.315399499008, 10712.303700259457, 10975.243960966207, 10998.780024399308, 9323.440593564408, 9426.104520070583, 9503.382280151767, 9870.901290987285, 9922.503874806087, 8539.0, 8695.304375649806, 8712.12878712149, 8721.956390218009, 8918.0], "value": 5971.27014320155, "unit": "score"}], "name": "dromaeo_css", "value": 6339.382198876964, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 111.0, 111.0, 111.0, 112.0, 114.0, 112.0, 112.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 130.0, 159.0, 213.0, 134.0, 155.0, 156.0, 130.0, 198.0, 133.0], "value": 156.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 162.0, 164.0, 160.0, 162.0, 160.0, 161.0, 159.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 152.0, 77.0, 75.0, 76.0, 74.0, 126.0, 74.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 150.0, 94.0, 94.0, 93.0, 129.0, 92.0, 92.0, 118.0], "value": 104.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0, 146.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 66.0, 66.0, 66.0, 124.0, 64.0, 64.0], "value": 71.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 64.0, 63.0, 64.0, 64.0, 71.0, 70.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 88.0, 85.0, 87.0, 83.0, 83.0, 89.0, 83.0, 88.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 136.0, 129.0, 127.0, 129.0, 128.0, 131.0, 277.0, 150.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 165.0, 166.0, 165.0, 166.0, 167.0, 166.0, 166.0, 169.0, 163.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 70.0, 70.0, 64.0, 64.0, 64.0, 67.0, 65.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1585.2, "alertThreshold": 2.0}]} 16:02:52 INFO - Return code: 0 16:02:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:02:52 ERROR - # TBPL SUCCESS # 16:02:52 INFO - Running post-action listener: _resource_record_post_action 16:02:52 INFO - ##### 16:02:52 INFO - ##### Finished run-tests step (success) 16:02:52 INFO - ##### 16:02:52 INFO - Running post-run listener: _resource_record_post_run 16:02:52 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 525918208; Read time: 0; Write time: 603564 16:02:52 INFO - TinderboxPrint: CPU usage
13.2% 16:02:52 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:02:52 INFO - TinderboxPrint: I/O write bytes / time
525,918,208 / 603,564 16:02:52 INFO - TinderboxPrint: CPU idle
8,330.2 (86.8%) 16:02:52 INFO - TinderboxPrint: CPU user
1,217.4 (12.7%) 16:02:52 INFO - TinderboxPrint: Swap in / out
0 / 0 16:02:52 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236355584; Read time: 0; Write time: 337812 16:02:52 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 289562624; Read time: 0; Write time: 265752 16:02:52 INFO - Running post-run listener: _upload_blobber_files 16:02:52 INFO - Blob upload gear active. 16:02:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:02:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:02:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:02:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:57 INFO - (blobuploader) - INFO - Done attempting. 16:02:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:02:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:58 INFO - (blobuploader) - INFO - Done attempting. 16:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:59 INFO - (blobuploader) - INFO - Done attempting. 16:02:59 INFO - (blobuploader) - INFO - Iteration through files over. 16:02:59 INFO - Return code: 0 16:02:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:02:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:02:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf80e494a1ad99c9974f80f59519fb75b02573a8383ff6ae4e0bccaada0b48c718ddecaf69d4a260586b54b1775040387a7b0fd4d7633a842716e54640e0e71f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33ecec90f293fdd131aec293ba0d5eaff2f8b0fa733b1ec49260223ba1a62f296a7dd0abea286bc59014da4a28100642490b3ca8d4e973d95d07dbc1385cf303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6daa799890e41299a997a026ccdf660971ea4fca366c9022af8317eed8748182b75fe8f0cd9474411b427442abdc1dba3ae2de7c8379466186e1cd11711536f5"} 16:02:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:02:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:02:59 INFO - Contents: 16:02:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf80e494a1ad99c9974f80f59519fb75b02573a8383ff6ae4e0bccaada0b48c718ddecaf69d4a260586b54b1775040387a7b0fd4d7633a842716e54640e0e71f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33ecec90f293fdd131aec293ba0d5eaff2f8b0fa733b1ec49260223ba1a62f296a7dd0abea286bc59014da4a28100642490b3ca8d4e973d95d07dbc1385cf303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6daa799890e41299a997a026ccdf660971ea4fca366c9022af8317eed8748182b75fe8f0cd9474411b427442abdc1dba3ae2de7c8379466186e1cd11711536f5"} 16:02:59 INFO - Running post-run listener: copy_logs_to_upload_dir 16:02:59 INFO - Copying logs to upload dir... 16:02:59 INFO - mkdir: /builds/slave/test/build/upload/logs 16:02:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.224137 ========= master_lag: 29.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-17 16:03:28.729737) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 22 secs) (at 2016-05-17 16:03:28.735008) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf80e494a1ad99c9974f80f59519fb75b02573a8383ff6ae4e0bccaada0b48c718ddecaf69d4a260586b54b1775040387a7b0fd4d7633a842716e54640e0e71f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33ecec90f293fdd131aec293ba0d5eaff2f8b0fa733b1ec49260223ba1a62f296a7dd0abea286bc59014da4a28100642490b3ca8d4e973d95d07dbc1385cf303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6daa799890e41299a997a026ccdf660971ea4fca366c9022af8317eed8748182b75fe8f0cd9474411b427442abdc1dba3ae2de7c8379466186e1cd11711536f5"} build_url:https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/R_KOGq31RV-ZcsJPX6-Tzw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf80e494a1ad99c9974f80f59519fb75b02573a8383ff6ae4e0bccaada0b48c718ddecaf69d4a260586b54b1775040387a7b0fd4d7633a842716e54640e0e71f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/33ecec90f293fdd131aec293ba0d5eaff2f8b0fa733b1ec49260223ba1a62f296a7dd0abea286bc59014da4a28100642490b3ca8d4e973d95d07dbc1385cf303", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6daa799890e41299a997a026ccdf660971ea4fca366c9022af8317eed8748182b75fe8f0cd9474411b427442abdc1dba3ae2de7c8379466186e1cd11711536f5"}' ========= master_lag: 22.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 22 secs) (at 2016-05-17 16:03:50.782392) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:03:50.782876) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1463524932.914025-359808549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004642 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:03:50.809986) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:03:50.810377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:03:50.810778) ========= ========= Total master_lag: 51.32 =========