builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-051 starttime: 1463717598.44 results: success (0) buildid: 20160519184305 builduid: cd341bfa9ed74804b7af33acbbf8b700 revision: c67dc1f9fab86d4f2cf3224307809c44fe3ce820 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.436366) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.436874) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.437201) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.486760) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.487052) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-19 21:13:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|: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.4M=0s 2016-05-19 21:13:19 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120400 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.622365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.622746) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017136 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.673624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:18.674064) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c67dc1f9fab86d4f2cf3224307809c44fe3ce820 --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 c67dc1f9fab86d4f2cf3224307809c44fe3ce820 --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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-19 21:13:19,190 truncating revision to first 12 chars 2016-05-19 21:13:19,190 Setting DEBUG logging. 2016-05-19 21:13:19,190 attempt 1/10 2016-05-19 21:13:19,190 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c67dc1f9fab8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 21:13:19,465 unpacking tar archive at: mozilla-central-c67dc1f9fab8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.592416 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:19.279872) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:19.280247) ========= script_repo_revision: c67dc1f9fab86d4f2cf3224307809c44fe3ce820 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:19.280719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:19.281102) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 21:13:19.290901) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-19 21:13:19.291238) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:13:19 INFO - MultiFileLogger online at 20160519 21:13:19 in /builds/slave/test 21:13:19 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 21:13:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:13:19 INFO - {'append_to_log': False, 21:13:19 INFO - 'base_work_dir': '/builds/slave/test', 21:13:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:13:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:13:19 INFO - 'branch': 'Firefox-Non-PGO', 21:13:19 INFO - 'buildbot_json_path': 'buildprops.json', 21:13:19 INFO - 'config_files': ('talos/linux_config.py',), 21:13:19 INFO - 'default_actions': ('clobber', 21:13:19 INFO - 'read-buildbot-config', 21:13:19 INFO - 'download-and-extract', 21:13:19 INFO - 'populate-webroot', 21:13:19 INFO - 'create-virtualenv', 21:13:19 INFO - 'install', 21:13:19 INFO - 'run-tests'), 21:13:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:13:19 INFO - 'download_minidump_stackwalk': True, 21:13:19 INFO - 'download_symbols': 'ondemand', 21:13:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:13:19 INFO - 'tooltool.py': '/tools/tooltool.py', 21:13:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:13:19 INFO - '/tools/misc-python/virtualenv.py')}, 21:13:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:13:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:13:19 INFO - 'installer_path': 'installer.exe', 21:13:19 INFO - 'log_level': 'info', 21:13:19 INFO - 'log_name': 'talos', 21:13:19 INFO - 'log_to_console': True, 21:13:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:13:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:13:19 INFO - 'opt_config_files': (), 21:13:19 INFO - 'pip_index': False, 21:13:19 INFO - 'sps_profile': False, 21:13:19 INFO - 'sps_profile_interval': 0, 21:13:19 INFO - 'suite': 'dromaeojs', 21:13:19 INFO - 'system_bits': '32', 21:13:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:13:19 INFO - 'title': 'talos-linux64-ix-051', 21:13:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:13:19 INFO - 'use_talos_json': True, 21:13:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:13:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:13:19 INFO - 'work_dir': 'build'} 21:13:19 INFO - ##### 21:13:19 INFO - ##### Running clobber step. 21:13:19 INFO - ##### 21:13:19 INFO - Running pre-action listener: _resource_record_pre_action 21:13:19 INFO - Running main action method: clobber 21:13:19 INFO - rmtree: /builds/slave/test/build 21:13:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:13:20 INFO - Running post-action listener: _resource_record_post_action 21:13:20 INFO - ##### 21:13:20 INFO - ##### Finished clobber step (success) 21:13:20 INFO - ##### 21:13:20 INFO - ##### 21:13:20 INFO - ##### Running read-buildbot-config step. 21:13:20 INFO - ##### 21:13:20 INFO - Running pre-action listener: _resource_record_pre_action 21:13:20 INFO - Running main action method: read_buildbot_config 21:13:20 INFO - Using buildbot properties: 21:13:20 INFO - { 21:13:20 INFO - "project": "", 21:13:20 INFO - "product": "firefox", 21:13:20 INFO - "script_repo_revision": "production", 21:13:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:13:20 INFO - "repository": "", 21:13:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 21:13:20 INFO - "buildid": "20160519184305", 21:13:20 INFO - "pgo_build": "False", 21:13:20 INFO - "basedir": "/builds/slave/test", 21:13:20 INFO - "buildnumber": 89, 21:13:20 INFO - "slavename": "talos-linux64-ix-051", 21:13:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:13:20 INFO - "platform": "ubuntu64_hw", 21:13:20 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 21:13:20 INFO - "branch": "mozilla-central", 21:13:20 INFO - "repo_path": "mozilla-central", 21:13:20 INFO - "revision": "c67dc1f9fab86d4f2cf3224307809c44fe3ce820", 21:13:20 INFO - "stage_platform": "linux64", 21:13:20 INFO - "builduid": "cd341bfa9ed74804b7af33acbbf8b700", 21:13:20 INFO - "slavebuilddir": "test" 21:13:20 INFO - } 21:13:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:13:20 INFO - Running post-action listener: _resource_record_post_action 21:13:20 INFO - ##### 21:13:20 INFO - ##### Finished read-buildbot-config step (success) 21:13:20 INFO - ##### 21:13:20 INFO - ##### 21:13:20 INFO - ##### Running download-and-extract step. 21:13:20 INFO - ##### 21:13:20 INFO - Running pre-action listener: _resource_record_pre_action 21:13:20 INFO - Running main action method: download_and_extract 21:13:20 INFO - mkdir: /builds/slave/test/build/tests 21:13:20 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')]} 21:13:20 INFO - https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:13:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:13:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:13:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:23 INFO - Downloaded 1361 bytes. 21:13:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:13:23 INFO - Using the following test package requirements: 21:13:23 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:13:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:13:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:13:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'jsshell-linux-x86_64.zip'], 21:13:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:13:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:13:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:13:23 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:13:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:13:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:13:23 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:13:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:13: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')]} 21:13:23 INFO - https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:13:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:13:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:13:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:28 INFO - Downloaded 22813601 bytes. 21:13:28 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'] 21:13:28 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 21:13:30 INFO - Return code: 0 21:13:30 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 21:13:30 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')]} 21:13:30 INFO - https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:41 INFO - Downloaded 22813601 bytes. 21:13:41 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'] 21:13:41 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 21:13:42 INFO - Return code: 0 21:13:42 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')]} 21:13:42 INFO - https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:13:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:13:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:13:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:44 INFO - Downloaded 10840359 bytes. 21:13:44 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'] 21:13:44 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 21:13:44 INFO - Return code: 0 21:13: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')]} 21:13:44 INFO - https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:13:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:13:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:13:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KmiO5qaoQme2AzOiriRIVg/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 21:13:46 INFO - Downloaded 54808189 bytes. 21:13:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:13:46 INFO - mkdir: /builds/slave/test/properties 21:13:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:13:46 INFO - Writing to file /builds/slave/test/properties/build_url 21:13:46 INFO - Contents: 21:13:46 INFO - build_url:https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:13:47 INFO - Running post-action listener: _resource_record_post_action 21:13:47 INFO - Running post-action listener: set_extra_try_arguments 21:13:47 INFO - ##### 21:13:47 INFO - ##### Finished download-and-extract step (success) 21:13:47 INFO - ##### 21:13:47 INFO - ##### 21:13:47 INFO - ##### Running populate-webroot step. 21:13:47 INFO - ##### 21:13:47 INFO - Running pre-action listener: _resource_record_pre_action 21:13:47 INFO - Running main action method: populate_webroot 21:13:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:13:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:13:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:13:47 INFO - '--tppagecycles', 21:13:47 INFO - '7'], 21:13:47 INFO - 'tests': ['tsvgm']}}, 21:13:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:13:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:13:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:13:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:13:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:13:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:13:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['damp', 'tps']}, 21:13:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'tests': ['damp', 'tps']}, 21:13:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['dromaeo_dom']}, 21:13:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:13:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'other-e10s': {'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['a11yr', 21:13:47 INFO - 'ts_paint', 21:13:47 INFO - 'tpaint', 21:13:47 INFO - 'sessionrestore', 21:13:47 INFO - 'sessionrestore_no_auto_restore', 21:13:47 INFO - 'tabpaint']}, 21:13:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['tsvgx', 21:13:47 INFO - 'tsvgr_opacity', 21:13:47 INFO - 'tart', 21:13:47 INFO - 'tscrollx', 21:13:47 INFO - 'cart']}, 21:13:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:13:47 INFO - 'tsvgr_opacity', 21:13:47 INFO - 'tart', 21:13:47 INFO - 'tscrollx', 21:13:47 INFO - 'cart']}, 21:13:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'talos_options': ['--disable-e10s'], 21:13:47 INFO - 'tests': ['tp5o']}, 21:13:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'tests': ['tp5o']}, 21:13:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'talos_options': ['--disable-e10s', 21:13:47 INFO - '--xperf_path', 21:13:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:13:47 INFO - 'tests': ['tp5n']}, 21:13:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:13:47 INFO - 'talos_options': ['--xperf_path', 21:13:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:13:47 INFO - 'tests': ['tp5n']}}, 21:13:47 INFO - 'talos.zip': {'path': '', 21:13:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:13:47 INFO - Running post-action listener: _resource_record_post_action 21:13:47 INFO - ##### 21:13:47 INFO - ##### Finished populate-webroot step (success) 21:13:47 INFO - ##### 21:13:47 INFO - ##### 21:13:47 INFO - ##### Running create-virtualenv step. 21:13:47 INFO - ##### 21:13:47 INFO - Running pre-action listener: _resource_record_pre_action 21:13:47 INFO - Running main action method: create_virtualenv 21:13:47 INFO - Creating virtualenv /builds/slave/test/build/venv 21:13:47 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 21:13:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:13:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:13:47 INFO - Using real prefix '/usr' 21:13:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:13:47 INFO - Installing distribute.............................................................................................................................................................................................done. 21:13:49 INFO - Installing pip.................done. 21:13:49 INFO - Return code: 0 21:13:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:13:49 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')]} 21:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:49 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')]} 21:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:49 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:13:49 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 21:13:49 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 21:13:49 INFO - Using env: {'DISPLAY': ':0', 21:13:49 INFO - 'HOME': '/home/cltbld', 21:13:49 INFO - 'LANG': 'en_US.UTF-8', 21:13:49 INFO - 'LANGUAGE': 'en_US:en', 21:13:49 INFO - 'LOGNAME': 'cltbld', 21:13:49 INFO - 'MAIL': '/var/mail/cltbld', 21:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:49 INFO - 'MOZ_NO_REMOTE': '1', 21:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:49 INFO - 'NO_EM_RESTART': '1', 21:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:49 INFO - 'PWD': '/builds/slave/test', 21:13:49 INFO - 'SHELL': '/bin/bash', 21:13:49 INFO - 'SHLVL': '1', 21:13:49 INFO - 'TERM': 'linux', 21:13:49 INFO - 'TMOUT': '86400', 21:13:49 INFO - 'USER': 'cltbld', 21:13:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:49 INFO - '_': '/tools/buildbot/bin/python'} 21:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:49 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 21:13:49 INFO - Cleaning up... 21:13:49 INFO - Return code: 0 21:13:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:13:49 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')]} 21:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:49 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')]} 21:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:49 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:13:49 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 21:13:49 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 21:13:49 INFO - Using env: {'DISPLAY': ':0', 21:13:49 INFO - 'HOME': '/home/cltbld', 21:13:49 INFO - 'LANG': 'en_US.UTF-8', 21:13:49 INFO - 'LANGUAGE': 'en_US:en', 21:13:49 INFO - 'LOGNAME': 'cltbld', 21:13:49 INFO - 'MAIL': '/var/mail/cltbld', 21:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:49 INFO - 'MOZ_NO_REMOTE': '1', 21:13:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:49 INFO - 'NO_EM_RESTART': '1', 21:13:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:49 INFO - 'PWD': '/builds/slave/test', 21:13:49 INFO - 'SHELL': '/bin/bash', 21:13:49 INFO - 'SHLVL': '1', 21:13:49 INFO - 'TERM': 'linux', 21:13:49 INFO - 'TMOUT': '86400', 21:13:49 INFO - 'USER': 'cltbld', 21:13:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:49 INFO - '_': '/tools/buildbot/bin/python'} 21:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:50 INFO - Downloading/unpacking psutil>=3.1.1 21:13:50 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 21:13:50 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 21:13:50 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 21:13:50 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 21:13:50 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 21:13:50 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 21:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:13:52 INFO - Installing collected packages: psutil 21:13:52 INFO - Running setup.py install for psutil 21:13:52 INFO - building 'psutil._psutil_linux' extension 21:13:52 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 21:13:52 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 21:13:52 INFO - building 'psutil._psutil_posix' extension 21:13:52 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 21:13:52 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 21:13:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:13:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:13:52 INFO - Successfully installed psutil 21:13:52 INFO - Cleaning up... 21:13:52 INFO - Return code: 0 21:13:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:13:52 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')]} 21:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:52 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')]} 21:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:52 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:13:52 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 21:13:52 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 21:13:52 INFO - Using env: {'DISPLAY': ':0', 21:13:52 INFO - 'HOME': '/home/cltbld', 21:13:52 INFO - 'LANG': 'en_US.UTF-8', 21:13:52 INFO - 'LANGUAGE': 'en_US:en', 21:13:52 INFO - 'LOGNAME': 'cltbld', 21:13:52 INFO - 'MAIL': '/var/mail/cltbld', 21:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:52 INFO - 'MOZ_NO_REMOTE': '1', 21:13:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:52 INFO - 'NO_EM_RESTART': '1', 21:13:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:52 INFO - 'PWD': '/builds/slave/test', 21:13:52 INFO - 'SHELL': '/bin/bash', 21:13:52 INFO - 'SHLVL': '1', 21:13:52 INFO - 'TERM': 'linux', 21:13:52 INFO - 'TMOUT': '86400', 21:13:52 INFO - 'USER': 'cltbld', 21:13:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:52 INFO - '_': '/tools/buildbot/bin/python'} 21:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:13:52 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 21:13:52 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 21:13:52 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 21:13:52 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 21:13:52 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 21:13:52 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 21:13:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:13:54 INFO - Installing collected packages: mozsystemmonitor 21:13:54 INFO - Running setup.py install for mozsystemmonitor 21:13:54 INFO - Successfully installed mozsystemmonitor 21:13:54 INFO - Cleaning up... 21:13:55 INFO - Return code: 0 21:13:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:13:55 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')]} 21:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:55 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')]} 21:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:55 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:13:55 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 21:13:55 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 21:13:55 INFO - Using env: {'DISPLAY': ':0', 21:13:55 INFO - 'HOME': '/home/cltbld', 21:13:55 INFO - 'LANG': 'en_US.UTF-8', 21:13:55 INFO - 'LANGUAGE': 'en_US:en', 21:13:55 INFO - 'LOGNAME': 'cltbld', 21:13:55 INFO - 'MAIL': '/var/mail/cltbld', 21:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:55 INFO - 'MOZ_NO_REMOTE': '1', 21:13:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:55 INFO - 'NO_EM_RESTART': '1', 21:13:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:55 INFO - 'PWD': '/builds/slave/test', 21:13:55 INFO - 'SHELL': '/bin/bash', 21:13:55 INFO - 'SHLVL': '1', 21:13:55 INFO - 'TERM': 'linux', 21:13:55 INFO - 'TMOUT': '86400', 21:13:55 INFO - 'USER': 'cltbld', 21:13:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:55 INFO - '_': '/tools/buildbot/bin/python'} 21:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:55 INFO - Downloading/unpacking blobuploader==1.2.4 21:13:55 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 21:13:55 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 21:13:55 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 21:13:55 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 21:13:55 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 21:13:55 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 21:13:57 INFO - Downloading blobuploader-1.2.4.tar.gz 21:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:13:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:13:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 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 21:13:57 INFO - Downloading docopt-0.6.1.tar.gz 21:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:13:57 INFO - Installing collected packages: blobuploader, requests, docopt 21:13:57 INFO - Running setup.py install for blobuploader 21:13:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:13:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:13:57 INFO - Running setup.py install for requests 21:13:58 INFO - Running setup.py install for docopt 21:13:58 INFO - Successfully installed blobuploader requests docopt 21:13:58 INFO - Cleaning up... 21:13:58 INFO - Return code: 0 21:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:13:58 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')]} 21:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:58 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')]} 21:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:13:58 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:13:58 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 21:13:58 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 21:13:58 INFO - Using env: {'DISPLAY': ':0', 21:13:58 INFO - 'HOME': '/home/cltbld', 21:13:58 INFO - 'LANG': 'en_US.UTF-8', 21:13:58 INFO - 'LANGUAGE': 'en_US:en', 21:13:58 INFO - 'LOGNAME': 'cltbld', 21:13:58 INFO - 'MAIL': '/var/mail/cltbld', 21:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:13:58 INFO - 'MOZ_NO_REMOTE': '1', 21:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:13:58 INFO - 'NO_EM_RESTART': '1', 21:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:13:58 INFO - 'PWD': '/builds/slave/test', 21:13:58 INFO - 'SHELL': '/bin/bash', 21:13:58 INFO - 'SHLVL': '1', 21:13:58 INFO - 'TERM': 'linux', 21:13:58 INFO - 'TMOUT': '86400', 21:13:58 INFO - 'USER': 'cltbld', 21:13:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:13:58 INFO - '_': '/tools/buildbot/bin/python'} 21:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:13:58 INFO - Running setup.py (path:/tmp/pip-4v_qj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:13:58 INFO - Running setup.py (path:/tmp/pip-N8Yr_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:13:58 INFO - Running setup.py (path:/tmp/pip-F0A6jY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:13:58 INFO - Running setup.py (path:/tmp/pip-TK8UBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:13:59 INFO - Running setup.py (path:/tmp/pip-SLLMGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:13:59 INFO - Running setup.py (path:/tmp/pip-CJx6_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:13:59 INFO - Running setup.py (path:/tmp/pip-hX9JDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:13:59 INFO - Running setup.py (path:/tmp/pip-9ea_fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:13:59 INFO - Running setup.py (path:/tmp/pip-TV_cWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:13:59 INFO - Running setup.py (path:/tmp/pip-cjcALV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:13:59 INFO - Running setup.py (path:/tmp/pip-rXjr0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:13:59 INFO - Running setup.py (path:/tmp/pip-TvBUV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:13:59 INFO - Running setup.py (path:/tmp/pip-LNRllN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:13:59 INFO - Running setup.py (path:/tmp/pip-Ah7UwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:59 INFO - Running setup.py (path:/tmp/pip-2w4tNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:13:59 INFO - Running setup.py (path:/tmp/pip-VX1AGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:13:59 INFO - Running setup.py (path:/tmp/pip-jsOL6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:13:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:13:59 INFO - Running setup.py install for manifestparser 21:14:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for mozcrash 21:14:00 INFO - Running setup.py install for mozdebug 21:14:00 INFO - Running setup.py install for mozdevice 21:14:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for mozfile 21:14:00 INFO - Running setup.py install for mozhttpd 21:14:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for mozinfo 21:14:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for mozInstall 21:14:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for mozleak 21:14:00 INFO - Running setup.py install for mozlog 21:14:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:14:00 INFO - Running setup.py install for moznetwork 21:14:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Running setup.py install for mozprocess 21:14:01 INFO - Running setup.py install for mozprofile 21:14:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Running setup.py install for mozrunner 21:14:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Running setup.py install for mozscreenshot 21:14:01 INFO - Running setup.py install for moztest 21:14:01 INFO - Running setup.py install for mozversion 21:14:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:14:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:14:01 INFO - Cleaning up... 21:14:01 INFO - Return code: 0 21:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:14:01 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')]} 21:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:01 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')]} 21:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:01 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:14:01 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 21:14:01 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 21:14:01 INFO - Using env: {'DISPLAY': ':0', 21:14:01 INFO - 'HOME': '/home/cltbld', 21:14:01 INFO - 'LANG': 'en_US.UTF-8', 21:14:01 INFO - 'LANGUAGE': 'en_US:en', 21:14:01 INFO - 'LOGNAME': 'cltbld', 21:14:01 INFO - 'MAIL': '/var/mail/cltbld', 21:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:14:01 INFO - 'MOZ_NO_REMOTE': '1', 21:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:14:01 INFO - 'NO_EM_RESTART': '1', 21:14:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:14:01 INFO - 'PWD': '/builds/slave/test', 21:14:01 INFO - 'SHELL': '/bin/bash', 21:14:01 INFO - 'SHLVL': '1', 21:14:01 INFO - 'TERM': 'linux', 21:14:01 INFO - 'TMOUT': '86400', 21:14:01 INFO - 'USER': 'cltbld', 21:14:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:14:01 INFO - '_': '/tools/buildbot/bin/python'} 21:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:14:01 INFO - Running setup.py (path:/tmp/pip-wrfuMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:14:02 INFO - Running setup.py (path:/tmp/pip-k7Zc1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:14:02 INFO - Running setup.py (path:/tmp/pip-oGg83E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:14:02 INFO - Running setup.py (path:/tmp/pip-gO0eCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:14:02 INFO - Running setup.py (path:/tmp/pip-7Lrb4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:14:02 INFO - Running setup.py (path:/tmp/pip-9ibESJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:14:02 INFO - Running setup.py (path:/tmp/pip-XIcoVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:14:02 INFO - Running setup.py (path:/tmp/pip-WxJ1Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:14:02 INFO - Running setup.py (path:/tmp/pip-bvnifC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:14:02 INFO - Running setup.py (path:/tmp/pip-n0JHAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:14:02 INFO - Running setup.py (path:/tmp/pip-fmD45g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:14:02 INFO - Running setup.py (path:/tmp/pip-xRNstC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:14:02 INFO - Running setup.py (path:/tmp/pip-qeoJFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:14:02 INFO - Running setup.py (path:/tmp/pip-Z7M0BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:14:02 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)) 21:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:14:02 INFO - Running setup.py (path:/tmp/pip-T9wCmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:14:03 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)) 21:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:14:03 INFO - Running setup.py (path:/tmp/pip-4N9Wy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:14:03 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)) 21:14:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:14:03 INFO - Running setup.py (path:/tmp/pip-EJb_x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:14:03 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)) 21:14:03 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)) 21:14:03 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)) 21:14:03 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)) 21:14:03 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)) 21:14:03 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)) 21:14:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:14:03 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 21:14:03 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 21:14:03 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 21:14:03 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 21:14:03 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 21:14:03 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 21:14:05 INFO - Downloading blessings-1.6.tar.gz 21:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:14:05 INFO - Installing collected packages: blessings 21:14:05 INFO - Running setup.py install for blessings 21:14:05 INFO - Successfully installed blessings 21:14:05 INFO - Cleaning up... 21:14:05 INFO - Return code: 0 21:14:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:14:05 INFO - Reading from file tmpfile_stdout 21:14:05 INFO - Current package versions: 21:14:05 INFO - argparse == 1.2.1 21:14:05 INFO - blessings == 1.6 21:14:05 INFO - blobuploader == 1.2.4 21:14:05 INFO - docopt == 0.6.1 21:14:05 INFO - manifestparser == 1.1 21:14:05 INFO - mozInstall == 1.12 21:14:05 INFO - mozcrash == 0.17 21:14:05 INFO - mozdebug == 0.1 21:14:05 INFO - mozdevice == 0.48 21:14:05 INFO - mozfile == 1.2 21:14:05 INFO - mozhttpd == 0.7 21:14:05 INFO - mozinfo == 0.9 21:14:05 INFO - mozleak == 0.1 21:14:05 INFO - mozlog == 3.2 21:14:05 INFO - moznetwork == 0.27 21:14:05 INFO - mozprocess == 0.22 21:14:05 INFO - mozprofile == 0.28 21:14:05 INFO - mozrunner == 6.11 21:14:05 INFO - mozscreenshot == 0.1 21:14:05 INFO - mozsystemmonitor == 0.3 21:14:05 INFO - moztest == 0.7 21:14:05 INFO - mozversion == 1.4 21:14:05 INFO - psutil == 3.1.1 21:14:05 INFO - requests == 1.2.3 21:14:05 INFO - wsgiref == 0.1.2 21:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:14: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')]} 21:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14: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')]} 21:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:05 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:14:05 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 21:14:05 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 21:14:05 INFO - Using env: {'DISPLAY': ':0', 21:14:05 INFO - 'HOME': '/home/cltbld', 21:14:05 INFO - 'LANG': 'en_US.UTF-8', 21:14:05 INFO - 'LANGUAGE': 'en_US:en', 21:14:05 INFO - 'LOGNAME': 'cltbld', 21:14:05 INFO - 'MAIL': '/var/mail/cltbld', 21:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:14:05 INFO - 'MOZ_NO_REMOTE': '1', 21:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:14:05 INFO - 'NO_EM_RESTART': '1', 21:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:14:05 INFO - 'PWD': '/builds/slave/test', 21:14:05 INFO - 'SHELL': '/bin/bash', 21:14:05 INFO - 'SHLVL': '1', 21:14:05 INFO - 'TERM': 'linux', 21:14:05 INFO - 'TMOUT': '86400', 21:14:05 INFO - 'USER': 'cltbld', 21:14:05 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:14:05 INFO - '_': '/tools/buildbot/bin/python'} 21:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 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)) 21:14:05 INFO - Cleaning up... 21:14:05 INFO - Return code: 0 21:14:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:14: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')]} 21:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14: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')]} 21:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:05 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:14:05 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 21:14:05 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 21:14:05 INFO - Using env: {'DISPLAY': ':0', 21:14:05 INFO - 'HOME': '/home/cltbld', 21:14:05 INFO - 'LANG': 'en_US.UTF-8', 21:14:05 INFO - 'LANGUAGE': 'en_US:en', 21:14:05 INFO - 'LOGNAME': 'cltbld', 21:14:05 INFO - 'MAIL': '/var/mail/cltbld', 21:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:14:05 INFO - 'MOZ_NO_REMOTE': '1', 21:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:14:05 INFO - 'NO_EM_RESTART': '1', 21:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:14:05 INFO - 'PWD': '/builds/slave/test', 21:14:05 INFO - 'SHELL': '/bin/bash', 21:14:05 INFO - 'SHLVL': '1', 21:14:05 INFO - 'TERM': 'linux', 21:14:05 INFO - 'TMOUT': '86400', 21:14:05 INFO - 'USER': 'cltbld', 21:14:05 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:14:05 INFO - '_': '/tools/buildbot/bin/python'} 21:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:14:05 INFO - Downloading/unpacking jsonschema 21:14:05 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 21:14:05 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 21:14:05 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 21:14:05 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 21:14:05 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 21:14:05 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 21:14:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:14:08 INFO - Downloading/unpacking functools32 (from jsonschema) 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 INFO - Downloading functools32-3.2.3-2.tar.gz 21:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:14:08 INFO - warning: no files found matching '*.txt' 21:14:08 INFO - no previously-included directories found matching 'build' 21:14:08 INFO - no previously-included directories found matching 'dist' 21:14:08 INFO - no previously-included directories found matching '.git*' 21:14:08 INFO - Installing collected packages: jsonschema, functools32 21:14:08 INFO - Running setup.py install for functools32 21:14:08 INFO - warning: no files found matching '*.txt' 21:14:08 INFO - no previously-included directories found matching 'build' 21:14:08 INFO - no previously-included directories found matching 'dist' 21:14:08 INFO - no previously-included directories found matching '.git*' 21:14:08 INFO - Successfully installed jsonschema functools32 21:14:08 INFO - Cleaning up... 21:14:08 INFO - Return code: 0 21:14:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:14:08 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')]} 21:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:14:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:08 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')]} 21:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:14:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:14:08 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 0x7f17d6b29f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1408770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', '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 21:14:08 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 21:14:08 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 21:14:08 INFO - Using env: {'DISPLAY': ':0', 21:14:08 INFO - 'HOME': '/home/cltbld', 21:14:08 INFO - 'LANG': 'en_US.UTF-8', 21:14:08 INFO - 'LANGUAGE': 'en_US:en', 21:14:08 INFO - 'LOGNAME': 'cltbld', 21:14:08 INFO - 'MAIL': '/var/mail/cltbld', 21:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:14:08 INFO - 'MOZ_NO_REMOTE': '1', 21:14:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:14:08 INFO - 'NO_EM_RESTART': '1', 21:14:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:14:08 INFO - 'PWD': '/builds/slave/test', 21:14:08 INFO - 'SHELL': '/bin/bash', 21:14:08 INFO - 'SHLVL': '1', 21:14:08 INFO - 'TERM': 'linux', 21:14:08 INFO - 'TMOUT': '86400', 21:14:08 INFO - 'USER': 'cltbld', 21:14:08 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:14:08 INFO - '_': '/tools/buildbot/bin/python'} 21:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:14:08 INFO - Downloading/unpacking flake8 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:08 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 21:14:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:14:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:14:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:10 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 21:14:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:14:11 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 21:14:11 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 21:14:11 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 21:14:11 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 21:14:11 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 21:14:11 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 21:14:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:14:11 INFO - no previously-included directories found matching 'docs/_build' 21:14:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:14:11 INFO - Running setup.py install for pep8 21:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:14:11 INFO - no previously-included directories found matching 'docs/_build' 21:14:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:14:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:14:11 INFO - Cleaning up... 21:14:11 INFO - Return code: 0 21:14:11 INFO - Running post-action listener: _resource_record_post_action 21:14:11 INFO - Running post-action listener: _start_resource_monitoring 21:14:11 INFO - Starting resource monitoring. 21:14:11 INFO - ##### 21:14:11 INFO - ##### Finished create-virtualenv step (success) 21:14:11 INFO - ##### 21:14:11 INFO - ##### 21:14:11 INFO - ##### Running install step. 21:14:11 INFO - ##### 21:14:11 INFO - Running pre-action listener: _resource_record_pre_action 21:14:11 INFO - Running main action method: install 21:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:14:11 INFO - Reading from file tmpfile_stdout 21:14:11 INFO - Detecting whether we're running mozinstall >=1.0... 21:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:14:11 INFO - Reading from file tmpfile_stdout 21:14:11 INFO - Output received: 21:14:11 INFO - Usage: mozinstall [options] installer 21:14:11 INFO - Options: 21:14:11 INFO - -h, --help show this help message and exit 21:14:11 INFO - -d DEST, --destination=DEST 21:14:11 INFO - Directory to install application into. [default: 21:14:11 INFO - "/builds/slave/test"] 21:14:11 INFO - --app=APP Application being installed. [default: firefox] 21:14:11 INFO - mkdir: /builds/slave/test/build/application 21:14:11 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'] 21:14:11 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 21:14:27 INFO - Reading from file tmpfile_stdout 21:14:27 INFO - Output received: 21:14:27 INFO - /builds/slave/test/build/application/firefox/firefox 21:14:27 INFO - Running post-action listener: _resource_record_post_action 21:14:27 INFO - ##### 21:14:27 INFO - ##### Finished install step (success) 21:14:27 INFO - ##### 21:14:27 INFO - ##### 21:14:27 INFO - ##### Running run-tests step. 21:14:27 INFO - ##### 21:14:27 INFO - Running pre-action listener: _resource_record_pre_action 21:14:27 INFO - Running main action method: run_tests 21:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:14:27 INFO - Python 2.7.3 21:14:27 INFO - Return code: 0 21:14:27 INFO - grabbing minidump binary from tooltool 21:14:27 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')]} 21:14:27 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 0x14979c0>, '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 0x14988c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1498d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:14:27 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 21:14:27 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 21:14:27 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 21:14:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:14:27 INFO - Return code: 0 21:14:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:14:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:14:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:14:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:14:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:14:28 INFO - Return code: 0 21:14:28 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/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 21:14:28 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/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 21:14:28 INFO - Using env: {'DISPLAY': ':0', 21:14:28 INFO - 'HOME': '/home/cltbld', 21:14:28 INFO - 'LANG': 'en_US.UTF-8', 21:14:28 INFO - 'LANGUAGE': 'en_US:en', 21:14:28 INFO - 'LOGNAME': 'cltbld', 21:14:28 INFO - 'MAIL': '/var/mail/cltbld', 21:14:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:14:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:14:28 INFO - 'MOZ_NO_REMOTE': '1', 21:14:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:14:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:14:28 INFO - 'NO_EM_RESTART': '1', 21:14:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:14:28 INFO - 'PWD': '/builds/slave/test', 21:14:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:14:28 INFO - 'SHELL': '/bin/bash', 21:14:28 INFO - 'SHLVL': '1', 21:14:28 INFO - 'TERM': 'linux', 21:14:28 INFO - 'TMOUT': '86400', 21:14:28 INFO - 'USER': 'cltbld', 21:14:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026', 21:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:14:28 INFO - '_': '/tools/buildbot/bin/python'} 21:14:28 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/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 21:14:28 INFO - mozversion application_buildid: 20160519184305 21:14:28 INFO - mozversion application_changeset: c67dc1f9fab86d4f2cf3224307809c44fe3ce820 21:14:28 INFO - mozversion application_display_name: Nightly 21:14:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:14:28 INFO - mozversion application_name: Firefox 21:14:28 INFO - mozversion application_remotingname: firefox 21:14:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:14:28 INFO - mozversion application_vendor: Mozilla 21:14:28 INFO - mozversion application_version: 49.0a1 21:14:28 INFO - mozversion platform_buildid: 20160519184305 21:14:28 INFO - mozversion platform_changeset: c67dc1f9fab86d4f2cf3224307809c44fe3ce820 21:14:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:14:28 INFO - mozversion platform_version: 49.0a1 21:14:28 INFO - using testdate: 1463717668 21:14:28 INFO - actual date: 1463717668 21:14:28 INFO - starting webserver on 'localhost:52573' 21:14:28 INFO - SUITE-START | Running 2 tests 21:14:28 INFO - TEST-START | dromaeo_css 21:14:28 INFO - operating with platform_type : linux_ 21:14:28 INFO - Initialising browser for dromaeo_css test... 21:14:28 INFO - TEST-INFO | started process 9553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXzAcEy/profile http://localhost:52573/getInfo.html) 21:14:33 INFO - PROCESS | 9553 | __metrics Screen width/height:1600/1200 21:14:33 INFO - PROCESS | 9553 | colorDepth:24 21:14:33 INFO - PROCESS | 9553 | Browser inner width/height: 1024/697 21:14:33 INFO - PROCESS | 9553 | __metrics 21:14:34 ERROR - PROCESS | 9553 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:14:34 INFO - TEST-INFO | 9553: exit 0 21:14:34 INFO - Browser initialized. 21:14:34 INFO - Running cycle 1/1 for dromaeo_css test... 21:14:34 INFO - TEST-INFO | started process 9634 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXzAcEy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:14:46 INFO - PROCESS | 9634 | RSS: Main: 154406912 21:14:46 INFO - PROCESS | 9634 | 21:15:38 INFO - PROCESS | 9634 | 1463717738488 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] 21:17:49 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52573/tests/dromaeo/cssquery-ext.html) 21:17:51 INFO - PROCESS | 9634 | RSS: Main: 273780736 21:17:51 INFO - PROCESS | 9634 | 21:20:53 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-ext.html (next: http://localhost:52573/tests/dromaeo/cssquery-jquery.html) 21:20:54 INFO - PROCESS | 9634 | RSS: Main: 232759296 21:20:54 INFO - PROCESS | 9634 | 21:23:56 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52573/tests/dromaeo/cssquery-mootools.html) 21:23:57 INFO - PROCESS | 9634 | RSS: Main: 233517056 21:23:57 INFO - PROCESS | 9634 | 21:26:59 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52573/tests/dromaeo/cssquery-prototype.html) 21:27:00 INFO - PROCESS | 9634 | RSS: Main: 232992768 21:27:00 INFO - PROCESS | 9634 | 21:30:02 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52573/tests/dromaeo/cssquery-yui.html) 21:30:03 INFO - PROCESS | 9634 | RSS: Main: 208437248 21:30:03 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | Cycle 1(1): loaded http://localhost:52573/tests/dromaeo/cssquery-yui.html (next: http://localhost:52573/tests/dromaeo/cssquery-dojo.html) 21:33:06 INFO - PROCESS | 9634 | RSS: Main: 259928064 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | __start_tp_report 21:33:06 INFO - PROCESS | 9634 | _x_x_mozilla_page_load 21:33:06 INFO - PROCESS | 9634 | _x_x_mozilla_page_load_details 21:33:06 INFO - PROCESS | 9634 | |i|pagename|runs| 21:33:06 INFO - PROCESS | 9634 | |0;dojo.html;2665.4802313548867;2665.5735082386814;2705.3642394037397;2706.364004458952;2712.620233167357;4595.954040459595;4636.09596128755;4693.718376897388;4708.74101924394;4763.5950944169745;4632.467266264381;4643.373144625474;4674.368960190373;4678.883027924304;4681.11058898809;4095.856645017425;4249.745015299084;4331.913361732763;4357.782110894458;4511.390962220107;4412.624926881203;4448.6885917985755;4462.843800466984;4469.575390337913;4498.685092043558;4245.214635292466;4292.527821939584;4312.5903039211225;4354.368616550614;4364.388985542027;4253.29820579604;4276.102018576103;4289.935650965238;4323.1785960667685;4373.650107991353;4316.978415107905;4318.784060796947;4325.502567204749;4331.9783401083105;4365.672574556889;4475.239209334421;4516.299973504112;4547;4562.908741825178;4618.5843274105155;5011.1481048221885;5031.597472202215;5054.62090343222;5056.646034777567;5061.215511595708;5027;5043.672161309503;5047.64666473347;5058.873528161788;5074.238864170367;4594.563516465931;4686.671932964694;4735.692180008357;4803.063402636522;4807.567318941312;5231.738413079331;5250.107481728115;5252.212168174728;5265.131253343156;5265.868353291198;4980.850574482772;4990.3762029746285;4990.80036798524;4993.325901003318;5043;4929.975350123298;5019.749012549358;5054.9241761373605;5058.696478211319;5081.898362032739;4991.900161996812;5025.522575355335;5025.874353141201;5028.371453568304;5079.161938280216;4842.443119041355;4969.82605608802;4977.576905962961;4978.77595508203;5031.823886163967;4151.4395556600075;4214.3889136075095;4241.066965267636;4246.5116511600945;4250.298700714347;3733.309337772521;3733.9439908401396;3735.9439608405896;3741.1956429367815;3777.9433308500393;4295.570442955679;4411.272140096912;4416.227160246944;4430.379746835446;4478.14915166124;5004.874878128076;5048.29323894655;5058.140116180258;5075.137226671474;5076.340075790124;4418.734875907456;4425.380446737459;4433.246348120827;4445.2887537993765;4457.487388950312;4448.621867141264;4537.77311134442;4555.293929440876;4556.521565235669;4594.333821595917;4804.807807687654;4835.879103022453;4854.368932038812;4864.343313652753;4904.656674032854;5053;5056.165732654071;5061.240813877947;5067.467915868928;5071.822486212965;4646.3030545417105;4670.902337950647;4673.135469938073;4778.378810754719;4830.975845120752;4864.416270047617;4891.070696567641;4894.437139728834;4940.604751619933;4944.110060189057;4936.284238785284;4959.776810043629;4961.2806143109765;4965.230389289666;4969.35398398206;4708.788104535236;4835.347228124299;4837.129316723023;4865;4874.366332376769;4875.926861097016;4878.658493905535;4890.070886531547;4904.313396124617;4918.508149185053;4808.927866082082;4817.566419022305;4823.541763532458;4882.877928051827;4886.071646387175;4797.064572408347;4828.637852161031;4871.318015477907;4883.95116048835;4887.315775791321;4745.5966242869745;4747.64392670558;4751.976240118777;4753.524647535219;4815.759212039454 21:33:06 INFO - PROCESS | 9634 | |1;ext.html;538.6766076421248;576.4926864359364;591.1841658511253;598.1536126381171;598.4015984015984;40280.39579406308;41181.17637647249;41729.79135104328;41961.580384196146;41999.79000105003;38847.223055538874;43221;43278.56721432785;43332.350014749805;43386.34920476195;7643.350315223212;7773.300402963732;7951.324137448295;7958.323542498894;10141.847872281922;9890.505474726235;10040.347377420445;10276.075153236245;10276.691699249035;10279.074883260506;10311.175105991502;10394.220433467483;10405.219608529353;10407.739806504822;10411.375317480937;10257.589696412171;10290.588376464932;10349.068583827491;10358.689239322832;10360.222983276246;10137.138343240833;10195.286329956905;10199.0820826126;10254.692359229235;10274.794504109876;39593.60406395928;39987.200255994714;40113.799431002655;40119;40180.79909600462;3117.0960421477744;3171.0962375722806;3274.7707660463775;3330.317284956578;3430.365382404263;30220.24449388761;30420;30557.38885222283;30783.69216307853;31180.688193118007;3417.9829100854586;3516.9120771980647;3555.822208889571;3623.3477973964677;3638.981805090984;278447;279668;279995.6000220006;280915;282169;58381;58445.57098719137;58529;58797;58930;63164.68417657882;63205.36794631995;63347;63407.87848789906;63416.36583634197;14694.19181944982;15255.313510892036;15285.617859553378;15331.540053798404;15333.923330383499;10578;10601;10643.095336896296;11118.388488633042;11136.276142050903;19766.505837353892;19855.702164467544;20002.69995950062;20021.09905054276;20106;7404.666789994463;7487;7511.540075535036;7614.086309642878;7762.611869406507;805.7341077444429;867.9392442528963;932.5430539035959;949.8242825077383;992.503748125937;28180.43639127206;28324.735485856898;28545.57181642277;29417.411651766844;29450.263743406158;1008.621766837436;1014.8579198912158;1017.3031473440717;1019.7705516258931;1128.8024595695886;15171.772423413657;15191;15253.161076140697;15479.326309067608;15701.371945122295;432604;437660;438002;438493;439038;51048.17138714611;51129.48870511322;51420.74289628528;51471.74264128655;51702;1117.8714447838602;1208.1844754790657;1245.439552201513;1259.703969567151;1273.2424207596466;27889.86055069793;30012.849935750182;30276;30520.542191867586;30583.694163058084;42551.7872410636;42769.78615106905;43117.784411077744;43177.56822431736;43198.78400608103;247506;249570;249727;250992;251134;32461.51307730433;34016.82991585026;38802;41106.38340424956;41262.38106428346;40557.39163912603;44954.55045449635;45211;45857.312140317255;46105.538944610125;37810.83603227763;38045;38316;38524;38930.416043759935;37190.08264462823;37698;37706.62293377031;37801.432978504796;37911.2417751649;36597.817010914776;37001.629983699815;37267.44098838574;37281;37694.434583481816;32959.50560741639;34056.637734490345;34445.31109377849;34663.826680866434;34679.826600866836;26186.73813261843;26309.605355919295;26519.734802651725;26530.204093877215;26871.731282686924 21:33:06 INFO - PROCESS | 9634 | |2;jquery.html;365.74215178299283;370.20221422833805;374.2104160221932;385.2814500955715;386.63269893601085;16182.352705891764;17351.913240433812;17755.37856175034;17818.910905445486;18086.728699069507;16003.83996160038;16656.1671916404;16661.83338166621;16725;17437.128143592832;4689.062187562493;4780.067886762083;4863.076015557032;4863.854084377456;4879;3535.275268569937;4806.855794326176;5313.920291195635;5570;5591.552675785948;5633.549316054706;5649.12438572022;5655.830325090254;5664.518515926131;5672.40439753824;5459.262999495097;5569.749361278732;5608.523275521586;5674.914876276859;5675.318961724599;3436.6906978371817;5025.1959686450355;5317.893642127175;5331.306930099062;5392.811251606175;12873.420696068699;12972.481100755958;13018.739625207538;15661.765073523906;21284.574308513897;1921.5388306806406;2005.6189324028387;2067.431456349501;2166.274298110135;2324.7210334759766;9270.258379329638;9330.766730831783;9613.230941524662;9678;9858.802823943553;1688.8480036796748;1724.4481765834937;1748.62404583014;1754.2632094520332;1799.6580649676519;69889.30110698879;70033.29966700319;72831.63584182045;73137;73739.63130184368;10101.747456313504;10103.242256830767;10314.48427578618;10407;10535.157187424988;14380.280985950661;14494.275286235646;14527.273636318141;14533.78199327011;14541.41834326615;7255.600941948243;7340.596267205355;7439.144498382755;7497.737579184703;7541.132769731441;6873.312668733187;6952.478564107712;7068.681909314093;7078.416378022088;7104.325089116525;4401.427814384109;4433.756143412079;4527.47933987589;4534.818607255673;4584.9541504585195;1679.4961511546487;1756.2360373237682;1772.7784026996626;1787.6335351252962;1790.3107303688173;729.8613263480028;847.5550336073611;942.4439580647451;954.1412728544366;976.4873441443299;16492.83507164913;17104.914475427544;17311.9612823231;17627;17768.466945991382;871.1636828644446;872.3021582733787;887.1128871128872;901.3330135699536;912.2610685344891;9564.66523671682;9612.423254604746;9797.167240784613;9833.36083154607;9888.555015024342;402614;407840;408652;408953;414173;77009;78462.60768696234;79938;80096.19903800887;80122;840.0968958369776;915.9038300978434;921.6451666108462;937.8640097186006;942.2414955960518;12919.418626161658;14989.775153372926;15067.09597424158;15840.920795395949;16196.757048644044;36963.63036369709;37625;37665.36342456112;37673.246535069695;37935.43096853494;47644.7617761909;48769.2684609724;48978.75510622424;49243;49491;6871;6972.30276972319;7013;7318.890216646648;7321;11293.774124517628;12051.096167787276;12375.443105060114;12423;12424.378781061092;7946.721864734706;9641.42151470914;10416;10446.164306855586;10772.838407423738;10492.108170805566;10520.527126202185;10758.838617420588;10774;10852.294600850919;10443.68668939933;10700.250982431466;10740.194485413469;10830.241883068426;10956.561737530728;7873.448858580074;7936.960315198387;8119;8201.630926608435;10214.387136771817;9961.40231586107;10801.729956751144;11121.944390277997;11187.272827266203;11327.603533876274 21:33:06 INFO - PROCESS | 9634 | |3;mootools.html;1281.9807702884452;1422.0472283570011;1452.7457694903392;1554.35494269878;1715.3053013529525;6640.734370625175;7431.925680743191;7695.730649427272;7708.229177082296;7876.881846772303;7623.809404764884;7664.92335076649;7747.845043099134;7771.533707977525;7778.844423111548;1883.9434816955445;2169.3491952414292;2200.427888748921;2226.899789509476;2238.160689741347;2213.4798322394226;2235.1282999630157;2238.1159442020394;2239.2050821958214;2239.4177513846353;2241.1371621925596;2260.367097212783;2265.4336415896028;2265.6035193841094;2268.7617800130997;1796.6406718656256;2107.3361891004283;2152.300502336747;2176.5755677642865;2177.3032629558547;2190.6823510591034;2192.6382146945734;2202.5815095131875;2213.2806837777784;2214.7010153629376;7730.033745781778;7770.145284018753;7778.3777297816605;7778.727744528971;7779.3776497880035;4463.575960283768;4590.954090459086;4598.977005114953;4603.562661547147;4608.723476591415;2868;2877.4676684813376;2924.722151395614;2976.657684366304;2990.386970671007;888.5690440136528;908.7909780750465;910.5811326789685;911.567005672307;912.0879120879121;10634.46827658614;10637.042666160083;10688.679339619825;10693.304935179189;10733.516991735312;4233.238017156941;4354.259775838115;4391.407160033355;4434.312681534368;4436.26801577736;8994.86507702385;9002.954985225162;9070.455772653662;9081.81836363269;9093.545322733837;4593.150267200579;4594.95405045952;4633.212353899845;4645.674802763774;4647.512011238839;3645.325614761278;4041.6362527372676;4178.665706743514;4190.078182799779;4217.093324935092;3690.409534474471;3693.926121477555;3764.79293638847;3809.085819403323;3809.7523660961947;1214.168294718121;1216.1426194532833;1223.9265644061206;1224.1002862895764;1233.648410203088;707.3527722134293;739.7484855149168;803.1606970715616;898.9280857531381;905.6377449020445;5270.683758974474;5342.652727572696;5348.5186333230195;5371.033214021436;5463.289772329572;905.6241659711278;907.9649199912105;919.0946917286467;921.4056933277998;926.1201858234704;3552.4493703476;3757.8496860125288;3778.4899038629983;3831.6168383162017;3859.864904728321;9028.36801423894;9357;9480.762980925394;9484.383515071499;9485.66800161991;9262.120098590623;9278.907210927804;9297.442153470813;9306.90693093074;9341.252699784001;7198.676059577227;7277.38142257914;7433.888491672629;7448.329650331604;7451.888221676679;5393.164059570773;5421.484958928895;5424.83725488236;5570.5543556516195;5636.85907852307;8755.781105472413;8954.955225223832;9040.593173307085;9066.818663626822;9081.00108988024;8793.780155496164;9046.819063618823;9051.230645395215;9069.501177435304;9148.725538233864;5713.542916566582;5964.373740757332;5970.671613061149;6071.51427885777;6129.693515324305;7222.0611320528;7229.963850180716;7268.27317268269;7289.453291003302;7301.196868344584;6553.344665533408;6636.601803891782;6673.9666301668185;6691.397774200345;6806.625635589892;6628.469722422291;6634.734610615521;6663.500237482305;6667.633280169637;6777.762778302736;6446.9032964506505;6501.2198536175965;6514.3811337922825;6548.116004339353;6564.770233041821;4691.577758001797;4784.856454306377;4846.539578740014;4944.8763780905765;4962.851114466427;4659.254519276899;4974.651774375759;4995.700257984533;5053.469385714594;5065.733566608348 21:33:06 INFO - PROCESS | 9634 | |4;prototype.html;289.95360742281235;293.22149692566234;295.9319356547994;298.34662090022834;299.2056091078188;4871.951280487194;4882.804687812492;4919.11455937931;4929.309896614477;4945.406551213855;4725.905481890369;4745.90508189836;4745.928811067828;4799.616030717543;4829.661923665345;3628.274345130978;3738.3831667774784;3789.2800367930145;3861.864834730785;3866.845326186963;3792.4880141180865;3828.712846536507;3830.291396091718;3845.1348446599573;3855.691544676419;3606.2607165531135;3804.1060350817534;3815.351390263662;3816.9809150954206;3821.5414150301863;3658.652428019347;3804.3342415077527;3815.3895376739865;3820.3639488752674;3851.826667799956;3380.3070370573973;3383.4924761285756;3383.5601371821504;3405.3700065487965;3406.9097888675633;4571.817127314904;4603.4808513190565;4606.953930460686;4616.953830461686;4620.283856002291;4244.554321796198;4279.636230920375;4317.784110794448;4346.8913277168;4349;4992.176290911995;5002.324686167357;5017.49825017499;5023.849284521433;5036.622253330978;3671.2473942841652;3680.834362453696;3720.3489389356755;3722.678449150567;3732.6267373262726;4985.35190425246;5266.92099618506;5270.7350235943095;5271.762770675329;5320;3670.724695647837;3683.4106542953537;3684.8157592120288;3695.852165913387;3701.815419065873;3723.2739615774663;3730.8734021607424;3734.10381508436;3741;3747.913105199462;4279.101388708344;4280.614744672995;4288.185244803477;4306.20335237982;4307.612314891675;4791.448983366888;5046.369203849519;5052.823151189691;5054.519820617035;5065.201853342084;1277.329402063924;1289.4262053386153;1294.1199984010964;1307.5750381126172;1309.9476020959055;840.1472505407016;845.2139510255521;858.4891989266376;858.6651206029653;880.3793325705362;4396.252637051709;4433.534478879736;4439.822407103679;4457.977710111429;4502.594766471033;5123.2827404163445;5150.124478838607;5161.690298582097;5165.793368265227;5188.714620695898;4365.301551751704;4424.6681498887065;4483.50681425043;4509.864704058883;4514.68397212192;2722.4282900591097;2735.39821239327;2749.3676454415595;2754;2758.5724212747054;4860.659753817199;4864.610831133501;4865.318855360252;4888.633352498577;4903.313536104948;5004.647815526697;5197.8440646780655;5239.921401178985;5246.979912733625;5302.840914772563;4781.784819683053;4802.375691160197;4819.349387832598;4874.805007799649;4886.462489126193;5132.79468821243;5136.922946155885;5158.819441319536;5172.482751724948;5186.118359878754;4924.729139897197;5031.52200540948;5050.9747451263975;5074;5082.186850103965;4811.855644330676;4822.228443449171;4827.541383568555;4861;4885.2427873679635;3636.6908812751212;3659.670629643345;3673.7039350406;3685.74199806011;3694.2796154749567;4931.3589233399425;5059.620528460307;5141.717205553581;5150.536451719363;5162.7351298932;4916.975415122902;4927.605791536596;4951.306817045544;4960.851174464772;4980.103581355246;4921.237208232729;4944.121233931083;4945.431275403231;4995.850124496271;4995.925061124013;4733.881652958704;4843.515648435128;4849.830255941025;4894.769452161557;4898.363212782316;4853.781579828987;4959.528844759742;4994.625403094855;5088.313077734459;5124.923766009179;4913.926291105565;4954.628402869871;4980.551750342486;4989.55094041538;4998.075356059141 21:33:06 INFO - PROCESS | 9634 | |5;yui.html;625.0592857749089;641.6022066318883;747.7906186267846;768.642581199742;791.1416113516838;12540.122191446591;12585.370731463436;12591.118621696485;12834.229946203222;13320.866791332084;12303.077269204801;12334.383280835968;12343;12361.38193090344;12361.876381236185;620.426105852085;758.7534051433306;773.0954782904001;794.6463823598501;796.6853093028251;786.0842118931438;793.4604468961104;795.7294519863245;795.8010497375656;796.6892911764417;846.8687353460224;849.049065047145;849.5030407211789;849.762066621347;850.0224741547239;646.7833275852571;812.7277362083709;829.17911267845;834.5910503853129;836.0427310729181;600.3606159440226;603.2369053147728;605.4429924469499;609.1076572820839;613.5735663713721;12799.616011519669;12820.743585128339;12844.165129266568;12859.807102893465;12875.935620321932;12227.327496987662;12327.198732082476;12347.876521234763;12568.434420451102;12724.1729287596;10583.788324233472;10822.783544329148;10849.566017359297;10962.177836662202;10978;3935.980320098381;3936.5866584008554;3955.3473676843287;3966.504186976628;4001.099752555681;44912.550874491164;45717.54282457166;46401;46425;46653.5334646656;9619.326647134634;9621.615135394646;9986.10125088731;10067.345622534658;10072.74818129538;17854.375096871547;17960.55098622519;17984.730229046574;18010.63978720418;18038;6483.837904052436;6501.252355979028;6516.902246466307;6549.705263263165;6737.629430381376;3542.132177616449;3595.4606808978338;3630.7276954228537;3642.0894776305922;3684.2263124744104;2590.6114082887716;2670.5593577059567;2673.2381271337576;2675.7324267573476;2677.6251324814543;459.19640628899424;489.3833769450518;490.4874406245482;498.1730327656146;509.320057722944;450.23909592788084;511.8976204759063;554.2545276602963;566.5863919338823;590.1221266143109;7755.72854950074;7888.881666775003;7904.446688731849;8002.5598592077995;8054.637541310655;543.0890427309618;545.4191286280098;576.6338375131821;592.6947621974687;593.6260156101629;3857.247836671822;4004.139110091286;4012.0972781124597;4052.2705912935953;4118.9588104119175;58258.70870645619;62464.18911149009;62838;63064.684676576326;63086.68456657687;48596.27105593419;54259.72870135624;54355;55366.16950745742;56913.71543142258;47815.282770758466;48331.27503087457;48586.27120593194;49030.50969490331;49113.50886491161;6806.9319306808275;7067.399271062018;7165.964170179116;7205.207427183111;8617.353698472514;30916.381672366875;31580.842095789372;32059;32115.51826722648;32733.34533309273;40762.1847563053;41431.1713765729;41467.7926610365;41982.790086049376;42520.5747942529;8796.516191609522;8917.777055573662;8974.282057435521;9088.227500662253;9179.954100229455;9953.253505986935;10086.798264034825;10124.240681948737;10274.383536987805;10337.17302615804;8552.828943421222;9315.34792564514;9895.604175832887;10016.799664006534;10957.56169753201;11213.551457941918;11260.549578016788;11268.211225214483;11415.771684566429;11707.180497365103;10274.229432792421;10287.948560257151;10509.369437833755;11116.833247501132;11371;9078.81842363136;9138.68014619485;9449.48027858447;9721.368111072758;10086;9653;9774.951125244328;9916.801663966824;9949.303548751517;10094.899051009395 21:33:06 INFO - PROCESS | 9634 | __end_tp_report 21:33:06 INFO - PROCESS | 9634 | __start_cc_report 21:33:06 INFO - PROCESS | 9634 | _x_x_mozilla_cycle_collect,1135 21:33:06 INFO - PROCESS | 9634 | __end_cc_report 21:33:06 INFO - PROCESS | 9634 | __startTimestamp1463718786603__endTimestamp 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | ------- Summary: start ------- 21:33:06 INFO - PROCESS | 9634 | Number of tests: 6 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#0] dojo.html Cycles:165 Average:4650.33 Median:4797.06 stddev:476.18 (9.9%) stddev-sans-first:451.47 21:33:06 INFO - PROCESS | 9634 | Values: 2665.5 2665.6 2705.4 2706.4 2712.6 4596.0 4636.1 4693.7 4708.7 4763.6 4632.5 4643.4 4674.4 4678.9 4681.1 4095.9 4249.7 4331.9 4357.8 4511.4 4412.6 4448.7 4462.8 4469.6 4498.7 4245.2 4292.5 4312.6 4354.4 4364.4 4253.3 4276.1 4289.9 4323.2 4373.7 4317.0 4318.8 4325.5 4332.0 4365.7 4475.2 4516.3 4547.0 4562.9 4618.6 5011.1 5031.6 5054.6 5056.6 5061.2 5027.0 5043.7 5047.6 5058.9 5074.2 4594.6 4686.7 4735.7 4803.1 4807.6 5231.7 5250.1 5252.2 5265.1 5265.9 4980.9 4990.4 4990.8 4993.3 5043.0 4930.0 5019.7 5054.9 5058.7 5081.9 4991.9 5025.5 5025.9 5028.4 5079.2 4842.4 4969.8 4977.6 4978.8 5031.8 4151.4 4214.4 4241.1 4246.5 4250.3 3733.3 3733.9 3735.9 3741.2 3777.9 4295.6 4411.3 4416.2 4430.4 4478.1 5004.9 5048.3 5058.1 5075.1 5076.3 4418.7 4425.4 4433.2 4445.3 4457.5 4448.6 4537.8 4555.3 4556.5 4594.3 4804.8 4835.9 4854.4 4864.3 4904.7 5053.0 5056.2 5061.2 5067.5 5071.8 4646.3 4670.9 4673.1 4778.4 4831.0 4864.4 4891.1 4894.4 4940.6 4944.1 4936.3 4959.8 4961.3 4965.2 4969.4 4708.8 4835.3 4837.1 4865.0 4874.4 4875.9 4878.7 4890.1 4904.3 4918.5 4808.9 4817.6 4823.5 4882.9 4886.1 4797.1 4828.6 4871.3 4884.0 4887.3 4745.6 4747.6 4752.0 4753.5 4815.8 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#1] ext.html Cycles:180 Average:49477.82 Median:34554.57 stddev:87600.54 (253.5%) stddev-sans-first:87769.22 21:33:06 INFO - PROCESS | 9634 | Values: 538.7 576.5 591.2 598.2 598.4 40280.4 41181.2 41729.8 41961.6 41999.8 38847.2 43221.0 43278.6 43332.4 43386.3 7643.4 7773.3 7951.3 7958.3 10141.8 9890.5 10040.3 10276.1 10276.7 10279.1 10311.2 10394.2 10405.2 10407.7 10411.4 10257.6 10290.6 10349.1 10358.7 10360.2 10137.1 10195.3 10199.1 10254.7 10274.8 39593.6 39987.2 40113.8 40119.0 40180.8 3117.1 3171.1 3274.8 3330.3 3430.4 30220.2 30420.0 30557.4 30783.7 31180.7 3418.0 3516.9 3555.8 3623.3 3639.0 278447.0 279668.0 279995.6 280915.0 282169.0 58381.0 58445.6 58529.0 58797.0 58930.0 63164.7 63205.4 63347.0 63407.9 63416.4 14694.2 15255.3 15285.6 15331.5 15333.9 10578.0 10601.0 10643.1 11118.4 11136.3 19766.5 19855.7 20002.7 20021.1 20106.0 7404.7 7487.0 7511.5 7614.1 7762.6 805.7 867.9 932.5 949.8 992.5 28180.4 28324.7 28545.6 29417.4 29450.3 1008.6 1014.9 1017.3 1019.8 1128.8 15171.8 15191.0 15253.2 15479.3 15701.4 432604.0 437660.0 438002.0 438493.0 439038.0 51048.2 51129.5 51420.7 51471.7 51702.0 1117.9 1208.2 1245.4 1259.7 1273.2 27889.9 30012.8 30276.0 30520.5 30583.7 42551.8 42769.8 43117.8 43177.6 43198.8 247506.0 249570.0 249727.0 250992.0 251134.0 32461.5 34016.8 38802.0 41106.4 41262.4 40557.4 44954.6 45211.0 45857.3 46105.5 37810.8 38045.0 38316.0 38524.0 38930.4 37190.1 37698.0 37706.6 37801.4 37911.2 36597.8 37001.6 37267.4 37281.0 37694.4 32959.5 34056.6 34445.3 34663.8 34679.8 26186.7 26309.6 26519.7 26530.2 26871.7 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#2] jquery.html Cycles:180 Average:24857.74 Median:4417.59 stddev:67408.55 (1525.9%) stddev-sans-first:67572.57 21:33:06 INFO - PROCESS | 9634 | Values: 365.7 370.2 374.2 385.3 386.6 16182.4 17351.9 17755.4 17818.9 18086.7 16003.8 16656.2 16661.8 16725.0 17437.1 4689.1 4780.1 4863.1 4863.9 4879.0 3535.3 4806.9 5313.9 5570.0 5591.6 5633.5 5649.1 5655.8 5664.5 5672.4 5459.3 5569.7 5608.5 5674.9 5675.3 3436.7 5025.2 5317.9 5331.3 5392.8 12873.4 12972.5 13018.7 15661.8 21284.6 1921.5 2005.6 2067.4 2166.3 2324.7 9270.3 9330.8 9613.2 9678.0 9858.8 1688.8 1724.4 1748.6 1754.3 1799.7 69889.3 70033.3 72831.6 73137.0 73739.6 10101.7 10103.2 10314.5 10407.0 10535.2 14380.3 14494.3 14527.3 14533.8 14541.4 7255.6 7340.6 7439.1 7497.7 7541.1 6873.3 6952.5 7068.7 7078.4 7104.3 4401.4 4433.8 4527.5 4534.8 4585.0 1679.5 1756.2 1772.8 1787.6 1790.3 729.9 847.6 942.4 954.1 976.5 16492.8 17104.9 17312.0 17627.0 17768.5 871.2 872.3 887.1 901.3 912.3 9564.7 9612.4 9797.2 9833.4 9888.6 402614.0 407840.0 408652.0 408953.0 414173.0 77009.0 78462.6 79938.0 80096.2 80122.0 840.1 915.9 921.6 937.9 942.2 12919.4 14989.8 15067.1 15840.9 16196.8 36963.6 37625.0 37665.4 37673.2 37935.4 47644.8 48769.3 48978.8 49243.0 49491.0 6871.0 6972.3 7013.0 7318.9 7321.0 11293.8 12051.1 12375.4 12423.0 12424.4 7946.7 9641.4 10416.0 10446.2 10772.8 10492.1 10520.5 10758.8 10774.0 10852.3 10443.7 10700.3 10740.2 10830.2 10956.6 7873.4 7937.0 8119.0 8201.6 10214.4 9961.4 10801.7 11121.9 11187.3 11327.6 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#3] mootools.html Cycles:180 Average:5132.47 Median:5423.16 stddev:2823.44 (52.1%) stddev-sans-first:2816.53 21:33:06 INFO - PROCESS | 9634 | Values: 1282.0 1422.0 1452.7 1554.4 1715.3 6640.7 7431.9 7695.7 7708.2 7876.9 7623.8 7664.9 7747.8 7771.5 7778.8 1883.9 2169.3 2200.4 2226.9 2238.2 2213.5 2235.1 2238.1 2239.2 2239.4 2241.1 2260.4 2265.4 2265.6 2268.8 1796.6 2107.3 2152.3 2176.6 2177.3 2190.7 2192.6 2202.6 2213.3 2214.7 7730.0 7770.1 7778.4 7778.7 7779.4 4463.6 4591.0 4599.0 4603.6 4608.7 2868.0 2877.5 2924.7 2976.7 2990.4 888.6 908.8 910.6 911.6 912.1 10634.5 10637.0 10688.7 10693.3 10733.5 4233.2 4354.3 4391.4 4434.3 4436.3 8994.9 9003.0 9070.5 9081.8 9093.5 4593.2 4595.0 4633.2 4645.7 4647.5 3645.3 4041.6 4178.7 4190.1 4217.1 3690.4 3693.9 3764.8 3809.1 3809.8 1214.2 1216.1 1223.9 1224.1 1233.6 707.4 739.7 803.2 898.9 905.6 5270.7 5342.7 5348.5 5371.0 5463.3 905.6 908.0 919.1 921.4 926.1 3552.4 3757.8 3778.5 3831.6 3859.9 9028.4 9357.0 9480.8 9484.4 9485.7 9262.1 9278.9 9297.4 9306.9 9341.3 7198.7 7277.4 7433.9 7448.3 7451.9 5393.2 5421.5 5424.8 5570.6 5636.9 8755.8 8955.0 9040.6 9066.8 9081.0 8793.8 9046.8 9051.2 9069.5 9148.7 5713.5 5964.4 5970.7 6071.5 6129.7 7222.1 7230.0 7268.3 7289.5 7301.2 6553.3 6636.6 6674.0 6691.4 6806.6 6628.5 6634.7 6663.5 6667.6 6777.8 6446.9 6501.2 6514.4 6548.1 6564.8 4691.6 4784.9 4846.5 4944.9 4962.9 4659.3 4974.7 4995.7 5053.5 5065.7 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#4] prototype.html Cycles:180 Average:4180.29 Median:4795.53 stddev:1196.48 (24.9%) stddev-sans-first:1163.66 21:33:06 INFO - PROCESS | 9634 | Values: 290.0 293.2 295.9 298.3 299.2 4872.0 4882.8 4919.1 4929.3 4945.4 4725.9 4745.9 4745.9 4799.6 4829.7 3628.3 3738.4 3789.3 3861.9 3866.8 3792.5 3828.7 3830.3 3845.1 3855.7 3606.3 3804.1 3815.4 3817.0 3821.5 3658.7 3804.3 3815.4 3820.4 3851.8 3380.3 3383.5 3383.6 3405.4 3406.9 4571.8 4603.5 4607.0 4617.0 4620.3 4244.6 4279.6 4317.8 4346.9 4349.0 4992.2 5002.3 5017.5 5023.8 5036.6 3671.2 3680.8 3720.3 3722.7 3732.6 4985.4 5266.9 5270.7 5271.8 5320.0 3670.7 3683.4 3684.8 3695.9 3701.8 3723.3 3730.9 3734.1 3741.0 3747.9 4279.1 4280.6 4288.2 4306.2 4307.6 4791.4 5046.4 5052.8 5054.5 5065.2 1277.3 1289.4 1294.1 1307.6 1309.9 840.1 845.2 858.5 858.7 880.4 4396.3 4433.5 4439.8 4458.0 4502.6 5123.3 5150.1 5161.7 5165.8 5188.7 4365.3 4424.7 4483.5 4509.9 4514.7 2722.4 2735.4 2749.4 2754.0 2758.6 4860.7 4864.6 4865.3 4888.6 4903.3 5004.6 5197.8 5239.9 5247.0 5302.8 4781.8 4802.4 4819.3 4874.8 4886.5 5132.8 5136.9 5158.8 5172.5 5186.1 4924.7 5031.5 5051.0 5074.0 5082.2 4811.9 4822.2 4827.5 4861.0 4885.2 3636.7 3659.7 3673.7 3685.7 3694.3 4931.4 5059.6 5141.7 5150.5 5162.7 4917.0 4927.6 4951.3 4960.9 4980.1 4921.2 4944.1 4945.4 4995.9 4995.9 4733.9 4843.5 4849.8 4894.8 4898.4 4853.8 4959.5 4994.6 5088.3 5124.9 4913.9 4954.6 4980.6 4989.6 4998.1 21:33:06 INFO - PROCESS | 9634 | 21:33:06 INFO - PROCESS | 9634 | [#5] yui.html Cycles:180 Average:13488.19 Median:24542.83 stddev:16319.19 (66.5%) stddev-sans-first:16336.38 21:33:06 INFO - PROCESS | 9634 | Values: 625.1 641.6 747.8 768.6 791.1 12540.1 12585.4 12591.1 12834.2 13320.9 12303.1 12334.4 12343.0 12361.4 12361.9 620.4 758.8 773.1 794.6 796.7 786.1 793.5 795.7 795.8 796.7 846.9 849.0 849.5 849.8 850.0 646.8 812.7 829.2 834.6 836.0 600.4 603.2 605.4 609.1 613.6 12799.6 12820.7 12844.2 12859.8 12875.9 12227.3 12327.2 12347.9 12568.4 12724.2 10583.8 10822.8 10849.6 10962.2 10978.0 3936.0 3936.6 3955.3 3966.5 4001.1 44912.6 45717.5 46401.0 46425.0 46653.5 9619.3 9621.6 9986.1 10067.3 10072.7 17854.4 17960.6 17984.7 18010.6 18038.0 6483.8 6501.3 6516.9 6549.7 6737.6 3542.1 3595.5 3630.7 3642.1 3684.2 2590.6 2670.6 2673.2 2675.7 2677.6 459.2 489.4 490.5 498.2 509.3 450.2 511.9 554.3 566.6 590.1 7755.7 7888.9 7904.4 8002.6 8054.6 543.1 545.4 576.6 592.7 593.6 3857.2 4004.1 4012.1 4052.3 4119.0 58258.7 62464.2 62838.0 63064.7 63086.7 48596.3 54259.7 54355.0 55366.2 56913.7 47815.3 48331.3 48586.3 49030.5 49113.5 6806.9 7067.4 7166.0 7205.2 8617.4 30916.4 31580.8 32059.0 32115.5 32733.3 40762.2 41431.2 41467.8 41982.8 42520.6 8796.5 8917.8 8974.3 9088.2 9180.0 9953.3 10086.8 10124.2 10274.4 10337.2 8552.8 9315.3 9895.6 10016.8 10957.6 11213.6 11260.5 11268.2 11415.8 11707.2 10274.2 10287.9 10509.4 11116.8 11371.0 9078.8 9138.7 9449.5 9721.4 10086.0 9653.0 9775.0 9916.8 9949.3 10094.9 21:33:06 INFO - PROCESS | 9634 | -------- Summary: end -------- 21:33:06 INFO - PROCESS | 9634 | 21:33:07 INFO - TEST-INFO | 9634: exit 0 21:33:07 INFO - TEST-OK | dromaeo_css | took 1118556ms 21:33:07 INFO - TEST-START | kraken 21:33:07 INFO - operating with platform_type : linux_ 21:33:07 INFO - Initialising browser for kraken test... 21:33:07 INFO - TEST-INFO | started process 9817 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIJL6kU/profile http://localhost:52573/getInfo.html) 21:33:11 INFO - PROCESS | 9817 | __metrics Screen width/height:1600/1200 21:33:11 INFO - PROCESS | 9817 | colorDepth:24 21:33:11 INFO - PROCESS | 9817 | Browser inner width/height: 1024/697 21:33:11 INFO - PROCESS | 9817 | __metrics 21:33:12 ERROR - PROCESS | 9817 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:33:13 INFO - TEST-INFO | 9817: exit 0 21:33:13 INFO - Browser initialized. 21:33:13 INFO - Running cycle 1/1 for kraken test... 21:33:13 INFO - TEST-INFO | started process 9898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIJL6kU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:33:25 INFO - PROCESS | 9898 | RSS: Main: 154832896 21:33:25 INFO - PROCESS | 9898 | 21:33:28 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52573/tests/kraken/driver.html?testName=audio-beat-detection) 21:33:29 INFO - PROCESS | 9898 | RSS: Main: 210579456 21:33:29 INFO - PROCESS | 9898 | 21:33:32 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52573/tests/kraken/driver.html?testName=audio-dft) 21:33:32 INFO - PROCESS | 9898 | RSS: Main: 198406144 21:33:32 INFO - PROCESS | 9898 | 21:33:35 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52573/tests/kraken/driver.html?testName=audio-fft) 21:33:36 INFO - PROCESS | 9898 | RSS: Main: 267554816 21:33:36 INFO - PROCESS | 9898 | 21:33:38 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52573/tests/kraken/driver.html?testName=audio-oscillator) 21:33:39 INFO - PROCESS | 9898 | RSS: Main: 177057792 21:33:39 INFO - PROCESS | 9898 | 21:33:41 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52573/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:33:42 INFO - PROCESS | 9898 | RSS: Main: 180424704 21:33:42 INFO - PROCESS | 9898 | 21:33:45 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52573/tests/kraken/driver.html?testName=imaging-darkroom) 21:33:46 INFO - PROCESS | 9898 | RSS: Main: 216510464 21:33:46 INFO - PROCESS | 9898 | 21:33:49 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52573/tests/kraken/driver.html?testName=imaging-desaturate) 21:33:50 INFO - PROCESS | 9898 | RSS: Main: 215441408 21:33:50 INFO - PROCESS | 9898 | 21:33:53 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52573/tests/kraken/driver.html?testName=json-parse-financial) 21:33:54 INFO - PROCESS | 9898 | RSS: Main: 215887872 21:33:54 INFO - PROCESS | 9898 | 21:33:56 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52573/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:33:56 INFO - PROCESS | 9898 | RSS: Main: 210345984 21:33:56 INFO - PROCESS | 9898 | 21:33:58 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:33:59 INFO - PROCESS | 9898 | RSS: Main: 212824064 21:33:59 INFO - PROCESS | 9898 | 21:34:01 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:34:02 INFO - PROCESS | 9898 | RSS: Main: 217239552 21:34:02 INFO - PROCESS | 9898 | 21:34:05 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:34:05 INFO - PROCESS | 9898 | RSS: Main: 221978624 21:34:05 INFO - PROCESS | 9898 | 21:34:08 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:34:09 INFO - PROCESS | 9898 | RSS: Main: 220782592 21:34:09 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | Cycle 1(1): loaded http://localhost:52573/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52573/tests/kraken/driver.html?testName=ai-astar) 21:34:11 INFO - PROCESS | 9898 | RSS: Main: 267202560 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | __start_tp_report 21:34:11 INFO - PROCESS | 9898 | _x_x_mozilla_page_load 21:34:11 INFO - PROCESS | 9898 | _x_x_mozilla_page_load_details 21:34:11 INFO - PROCESS | 9898 | |i|pagename|runs| 21:34:11 INFO - PROCESS | 9898 | |0;ai-astar;114;111;114;115;115;114;114;113;113;113 21:34:11 INFO - PROCESS | 9898 | |1;audio-beat-detection;159;136;158;214;130;157;155;136;189;134 21:34:11 INFO - PROCESS | 9898 | |2;audio-dft;162;161;160;161;160;162;162;162;163;161 21:34:11 INFO - PROCESS | 9898 | |3;audio-fft;75;75;76;153;75;76;76;75;123;76 21:34:11 INFO - PROCESS | 9898 | |4;audio-oscillator;94;94;151;93;93;94;126;95;94;128 21:34:11 INFO - PROCESS | 9898 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 21:34:11 INFO - PROCESS | 9898 | |6;imaging-darkroom;146;146;147;146;146;146;147;147;146;145 21:34:11 INFO - PROCESS | 9898 | |7;imaging-desaturate;95;95;94;95;94;94;95;94;95;94 21:34:11 INFO - PROCESS | 9898 | |8;json-parse-financial;63;63;63;63;64;64;69;64;63;63 21:34:11 INFO - PROCESS | 9898 | |9;json-stringify-tinderbox;65;65;70;65;66;65;66;65;65;66 21:34:11 INFO - PROCESS | 9898 | |10;stanford-crypto-aes;82;82;88;81;84;81;82;83;146;82 21:34:11 INFO - PROCESS | 9898 | |11;stanford-crypto-ccm;141;134;140;131;130;137;128;128;156;137 21:34:11 INFO - PROCESS | 9898 | |12;stanford-crypto-pbkdf2;172;170;176;170;170;172;180;174;173;173 21:34:11 INFO - PROCESS | 9898 | |13;stanford-crypto-sha256-iterative;65;70;66;67;66;64;66;66;64;65 21:34:11 INFO - PROCESS | 9898 | __end_tp_report 21:34:11 INFO - PROCESS | 9898 | __start_cc_report 21:34:11 INFO - PROCESS | 9898 | _x_x_mozilla_cycle_collect,2085 21:34:11 INFO - PROCESS | 9898 | __end_cc_report 21:34:11 INFO - PROCESS | 9898 | __startTimestamp1463718851407__endTimestamp 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | ------- Summary: start ------- 21:34:11 INFO - PROCESS | 9898 | Number of tests: 14 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:1.17 (1.0%) stddev-sans-first:1.24 21:34:11 INFO - PROCESS | 9898 | Values: 114.0 111.0 114.0 115.0 115.0 114.0 114.0 113.0 113.0 113.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#1] audio-beat-detection Cycles:10 Average:156.80 Median:157.50 stddev:26.71 (17.0%) stddev-sans-first:28.32 21:34:11 INFO - PROCESS | 9898 | Values: 159.0 136.0 158.0 214.0 130.0 157.0 155.0 136.0 189.0 134.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 21:34:11 INFO - PROCESS | 9898 | Values: 162.0 161.0 160.0 161.0 160.0 162.0 162.0 162.0 163.0 161.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#3] audio-fft Cycles:10 Average:88.00 Median:75.50 stddev:27.29 (36.1%) stddev-sans-first:28.54 21:34:11 INFO - PROCESS | 9898 | Values: 75.0 75.0 76.0 153.0 75.0 76.0 76.0 75.0 123.0 76.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:94.00 stddev:20.93 (22.3%) stddev-sans-first:21.73 21:34:11 INFO - PROCESS | 9898 | Values: 94.0 94.0 151.0 93.0 93.0 94.0 126.0 95.0 94.0 128.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:34:11 INFO - PROCESS | 9898 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 21:34:11 INFO - PROCESS | 9898 | Values: 146.0 146.0 147.0 146.0 146.0 146.0 147.0 147.0 146.0 145.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 21:34:11 INFO - PROCESS | 9898 | Values: 95.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:63.50 stddev:1.85 (2.9%) stddev-sans-first:1.94 21:34:11 INFO - PROCESS | 9898 | Values: 63.0 63.0 63.0 63.0 64.0 64.0 69.0 64.0 63.0 63.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:65.50 stddev:1.55 (2.4%) stddev-sans-first:1.62 21:34:11 INFO - PROCESS | 9898 | Values: 65.0 65.0 70.0 65.0 66.0 65.0 66.0 65.0 65.0 66.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:82.00 stddev:20.10 (24.5%) stddev-sans-first:21.15 21:34:11 INFO - PROCESS | 9898 | Values: 82.0 82.0 88.0 81.0 84.0 81.0 82.0 83.0 146.0 82.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:137.00 stddev:8.40 (6.1%) stddev-sans-first:8.73 21:34:11 INFO - PROCESS | 9898 | Values: 141.0 134.0 140.0 131.0 130.0 137.0 128.0 128.0 156.0 137.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.00 stddev:3.13 (1.8%) stddev-sans-first:3.30 21:34:11 INFO - PROCESS | 9898 | Values: 172.0 170.0 176.0 170.0 170.0 172.0 180.0 174.0 173.0 173.0 21:34:11 INFO - PROCESS | 9898 | 21:34:11 INFO - PROCESS | 9898 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.73 (2.6%) stddev-sans-first:1.80 21:34:11 INFO - PROCESS | 9898 | Values: 65.0 70.0 66.0 67.0 66.0 64.0 66.0 66.0 64.0 65.0 21:34:11 INFO - PROCESS | 9898 | -------- Summary: end -------- 21:34:11 INFO - PROCESS | 9898 | 21:34:12 INFO - TEST-INFO | 9898: exit 0 21:34:12 INFO - TEST-OK | kraken | took 65084ms 21:34:12 INFO - SUITE-END | took 1183s 21:34:12 INFO - Completed test suite (00:19:43) 21:34:12 INFO - javascript benchmark 21:34:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2665.4802313548867, 2665.5735082386814, 2705.3642394037397, 2706.364004458952, 2712.620233167357, 4595.954040459595, 4636.09596128755, 4693.718376897388, 4708.74101924394, 4763.5950944169745, 4632.467266264381, 4643.373144625474, 4674.368960190373, 4678.883027924304, 4681.11058898809, 4095.856645017425, 4249.745015299084, 4331.913361732763, 4357.782110894458, 4511.390962220107, 4412.624926881203, 4448.6885917985755, 4462.843800466984, 4469.575390337913, 4498.685092043558, 4245.214635292466, 4292.527821939584, 4312.5903039211225, 4354.368616550614, 4364.388985542027, 4253.29820579604, 4276.102018576103, 4289.935650965238, 4323.1785960667685, 4373.650107991353, 4316.978415107905, 4318.784060796947, 4325.502567204749, 4331.9783401083105, 4365.672574556889, 4475.239209334421, 4516.299973504112, 4547.0, 4562.908741825178, 4618.5843274105155, 5011.1481048221885, 5031.597472202215, 5054.62090343222, 5056.646034777567, 5061.215511595708, 5027.0, 5043.672161309503, 5047.64666473347, 5058.873528161788, 5074.238864170367, 4594.563516465931, 4686.671932964694, 4735.692180008357, 4803.063402636522, 4807.567318941312, 5231.738413079331, 5250.107481728115, 5252.212168174728, 5265.131253343156, 5265.868353291198, 4980.850574482772, 4990.3762029746285, 4990.80036798524, 4993.325901003318, 5043.0, 4929.975350123298, 5019.749012549358, 5054.9241761373605, 5058.696478211319, 5081.898362032739, 4991.900161996812, 5025.522575355335, 5025.874353141201, 5028.371453568304, 5079.161938280216, 4842.443119041355, 4969.82605608802, 4977.576905962961, 4978.77595508203, 5031.823886163967, 4151.4395556600075, 4214.3889136075095, 4241.066965267636, 4246.5116511600945, 4250.298700714347, 3733.309337772521, 3733.9439908401396, 3735.9439608405896, 3741.1956429367815, 3777.9433308500393, 4295.570442955679, 4411.272140096912, 4416.227160246944, 4430.379746835446, 4478.14915166124, 5004.874878128076, 5048.29323894655, 5058.140116180258, 5075.137226671474, 5076.340075790124, 4418.734875907456, 4425.380446737459, 4433.246348120827, 4445.2887537993765, 4457.487388950312, 4448.621867141264, 4537.77311134442, 4555.293929440876, 4556.521565235669, 4594.333821595917, 4804.807807687654, 4835.879103022453, 4854.368932038812, 4864.343313652753, 4904.656674032854, 5053.0, 5056.165732654071, 5061.240813877947, 5067.467915868928, 5071.822486212965, 4646.3030545417105, 4670.902337950647, 4673.135469938073, 4778.378810754719, 4830.975845120752, 4864.416270047617, 4891.070696567641, 4894.437139728834, 4940.604751619933, 4944.110060189057, 4936.284238785284, 4959.776810043629, 4961.2806143109765, 4965.230389289666, 4969.35398398206, 4708.788104535236, 4835.347228124299, 4837.129316723023, 4865.0, 4874.366332376769, 4875.926861097016, 4878.658493905535, 4890.070886531547, 4904.313396124617, 4918.508149185053, 4808.927866082082, 4817.566419022305, 4823.541763532458, 4882.877928051827, 4886.071646387175, 4797.064572408347, 4828.637852161031, 4871.318015477907, 4883.95116048835, 4887.315775791321, 4745.5966242869745, 4747.64392670558, 4751.976240118777, 4753.524647535219, 4815.759212039454], "value": 4621.003507313623, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.6766076421248, 576.4926864359364, 591.1841658511253, 598.1536126381171, 598.4015984015984, 40280.39579406308, 41181.17637647249, 41729.79135104328, 41961.580384196146, 41999.79000105003, 38847.223055538874, 43221.0, 43278.56721432785, 43332.350014749805, 43386.34920476195, 7643.350315223212, 7773.300402963732, 7951.324137448295, 7958.323542498894, 10141.847872281922, 9890.505474726235, 10040.347377420445, 10276.075153236245, 10276.691699249035, 10279.074883260506, 10311.175105991502, 10394.220433467483, 10405.219608529353, 10407.739806504822, 10411.375317480937, 10257.589696412171, 10290.588376464932, 10349.068583827491, 10358.689239322832, 10360.222983276246, 10137.138343240833, 10195.286329956905, 10199.0820826126, 10254.692359229235, 10274.794504109876, 39593.60406395928, 39987.200255994714, 40113.799431002655, 40119.0, 40180.79909600462, 3117.0960421477744, 3171.0962375722806, 3274.7707660463775, 3330.317284956578, 3430.365382404263, 30220.24449388761, 30420.0, 30557.38885222283, 30783.69216307853, 31180.688193118007, 3417.9829100854586, 3516.9120771980647, 3555.822208889571, 3623.3477973964677, 3638.981805090984, 278447.0, 279668.0, 279995.6000220006, 280915.0, 282169.0, 58381.0, 58445.57098719137, 58529.0, 58797.0, 58930.0, 63164.68417657882, 63205.36794631995, 63347.0, 63407.87848789906, 63416.36583634197, 14694.19181944982, 15255.313510892036, 15285.617859553378, 15331.540053798404, 15333.923330383499, 10578.0, 10601.0, 10643.095336896296, 11118.388488633042, 11136.276142050903, 19766.505837353892, 19855.702164467544, 20002.69995950062, 20021.09905054276, 20106.0, 7404.666789994463, 7487.0, 7511.540075535036, 7614.086309642878, 7762.611869406507, 805.7341077444429, 867.9392442528963, 932.5430539035959, 949.8242825077383, 992.503748125937, 28180.43639127206, 28324.735485856898, 28545.57181642277, 29417.411651766844, 29450.263743406158, 1008.621766837436, 1014.8579198912158, 1017.3031473440717, 1019.7705516258931, 1128.8024595695886, 15171.772423413657, 15191.0, 15253.161076140697, 15479.326309067608, 15701.371945122295, 432604.0, 437660.0, 438002.0, 438493.0, 439038.0, 51048.17138714611, 51129.48870511322, 51420.74289628528, 51471.74264128655, 51702.0, 1117.8714447838602, 1208.1844754790657, 1245.439552201513, 1259.703969567151, 1273.2424207596466, 27889.86055069793, 30012.849935750182, 30276.0, 30520.542191867586, 30583.694163058084, 42551.7872410636, 42769.78615106905, 43117.784411077744, 43177.56822431736, 43198.78400608103, 247506.0, 249570.0, 249727.0, 250992.0, 251134.0, 32461.51307730433, 34016.82991585026, 38802.0, 41106.38340424956, 41262.38106428346, 40557.39163912603, 44954.55045449635, 45211.0, 45857.312140317255, 46105.538944610125, 37810.83603227763, 38045.0, 38316.0, 38524.0, 38930.416043759935, 37190.08264462823, 37698.0, 37706.62293377031, 37801.432978504796, 37911.2417751649, 36597.817010914776, 37001.629983699815, 37267.44098838574, 37281.0, 37694.434583481816, 32959.50560741639, 34056.637734490345, 34445.31109377849, 34663.826680866434, 34679.826600866836, 26186.73813261843, 26309.605355919295, 26519.734802651725, 26530.204093877215, 26871.731282686924], "value": 18971.52556048085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.74215178299283, 370.20221422833805, 374.2104160221932, 385.2814500955715, 386.63269893601085, 16182.352705891764, 17351.913240433812, 17755.37856175034, 17818.910905445486, 18086.728699069507, 16003.83996160038, 16656.1671916404, 16661.83338166621, 16725.0, 17437.128143592832, 4689.062187562493, 4780.067886762083, 4863.076015557032, 4863.854084377456, 4879.0, 3535.275268569937, 4806.855794326176, 5313.920291195635, 5570.0, 5591.552675785948, 5633.549316054706, 5649.12438572022, 5655.830325090254, 5664.518515926131, 5672.40439753824, 5459.262999495097, 5569.749361278732, 5608.523275521586, 5674.914876276859, 5675.318961724599, 3436.6906978371817, 5025.1959686450355, 5317.893642127175, 5331.306930099062, 5392.811251606175, 12873.420696068699, 12972.481100755958, 13018.739625207538, 15661.765073523906, 21284.574308513897, 1921.5388306806406, 2005.6189324028387, 2067.431456349501, 2166.274298110135, 2324.7210334759766, 9270.258379329638, 9330.766730831783, 9613.230941524662, 9678.0, 9858.802823943553, 1688.8480036796748, 1724.4481765834937, 1748.62404583014, 1754.2632094520332, 1799.6580649676519, 69889.30110698879, 70033.29966700319, 72831.63584182045, 73137.0, 73739.63130184368, 10101.747456313504, 10103.242256830767, 10314.48427578618, 10407.0, 10535.157187424988, 14380.280985950661, 14494.275286235646, 14527.273636318141, 14533.78199327011, 14541.41834326615, 7255.600941948243, 7340.596267205355, 7439.144498382755, 7497.737579184703, 7541.132769731441, 6873.312668733187, 6952.478564107712, 7068.681909314093, 7078.416378022088, 7104.325089116525, 4401.427814384109, 4433.756143412079, 4527.47933987589, 4534.818607255673, 4584.9541504585195, 1679.4961511546487, 1756.2360373237682, 1772.7784026996626, 1787.6335351252962, 1790.3107303688173, 729.8613263480028, 847.5550336073611, 942.4439580647451, 954.1412728544366, 976.4873441443299, 16492.83507164913, 17104.914475427544, 17311.9612823231, 17627.0, 17768.466945991382, 871.1636828644446, 872.3021582733787, 887.1128871128872, 901.3330135699536, 912.2610685344891, 9564.66523671682, 9612.423254604746, 9797.167240784613, 9833.36083154607, 9888.555015024342, 402614.0, 407840.0, 408652.0, 408953.0, 414173.0, 77009.0, 78462.60768696234, 79938.0, 80096.19903800887, 80122.0, 840.0968958369776, 915.9038300978434, 921.6451666108462, 937.8640097186006, 942.2414955960518, 12919.418626161658, 14989.775153372926, 15067.09597424158, 15840.920795395949, 16196.757048644044, 36963.63036369709, 37625.0, 37665.36342456112, 37673.246535069695, 37935.43096853494, 47644.7617761909, 48769.2684609724, 48978.75510622424, 49243.0, 49491.0, 6871.0, 6972.30276972319, 7013.0, 7318.890216646648, 7321.0, 11293.774124517628, 12051.096167787276, 12375.443105060114, 12423.0, 12424.378781061092, 7946.721864734706, 9641.42151470914, 10416.0, 10446.164306855586, 10772.838407423738, 10492.108170805566, 10520.527126202185, 10758.838617420588, 10774.0, 10852.294600850919, 10443.68668939933, 10700.250982431466, 10740.194485413469, 10830.241883068426, 10956.561737530728, 7873.448858580074, 7936.960315198387, 8119.0, 8201.630926608435, 10214.387136771817, 9961.40231586107, 10801.729956751144, 11121.944390277997, 11187.272827266203, 11327.603533876274], "value": 8235.10181367226, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.9807702884452, 1422.0472283570011, 1452.7457694903392, 1554.35494269878, 1715.3053013529525, 6640.734370625175, 7431.925680743191, 7695.730649427272, 7708.229177082296, 7876.881846772303, 7623.809404764884, 7664.92335076649, 7747.845043099134, 7771.533707977525, 7778.844423111548, 1883.9434816955445, 2169.3491952414292, 2200.427888748921, 2226.899789509476, 2238.160689741347, 2213.4798322394226, 2235.1282999630157, 2238.1159442020394, 2239.2050821958214, 2239.4177513846353, 2241.1371621925596, 2260.367097212783, 2265.4336415896028, 2265.6035193841094, 2268.7617800130997, 1796.6406718656256, 2107.3361891004283, 2152.300502336747, 2176.5755677642865, 2177.3032629558547, 2190.6823510591034, 2192.6382146945734, 2202.5815095131875, 2213.2806837777784, 2214.7010153629376, 7730.033745781778, 7770.145284018753, 7778.3777297816605, 7778.727744528971, 7779.3776497880035, 4463.575960283768, 4590.954090459086, 4598.977005114953, 4603.562661547147, 4608.723476591415, 2868.0, 2877.4676684813376, 2924.722151395614, 2976.657684366304, 2990.386970671007, 888.5690440136528, 908.7909780750465, 910.5811326789685, 911.567005672307, 912.0879120879121, 10634.46827658614, 10637.042666160083, 10688.679339619825, 10693.304935179189, 10733.516991735312, 4233.238017156941, 4354.259775838115, 4391.407160033355, 4434.312681534368, 4436.26801577736, 8994.86507702385, 9002.954985225162, 9070.455772653662, 9081.81836363269, 9093.545322733837, 4593.150267200579, 4594.95405045952, 4633.212353899845, 4645.674802763774, 4647.512011238839, 3645.325614761278, 4041.6362527372676, 4178.665706743514, 4190.078182799779, 4217.093324935092, 3690.409534474471, 3693.926121477555, 3764.79293638847, 3809.085819403323, 3809.7523660961947, 1214.168294718121, 1216.1426194532833, 1223.9265644061206, 1224.1002862895764, 1233.648410203088, 707.3527722134293, 739.7484855149168, 803.1606970715616, 898.9280857531381, 905.6377449020445, 5270.683758974474, 5342.652727572696, 5348.5186333230195, 5371.033214021436, 5463.289772329572, 905.6241659711278, 907.9649199912105, 919.0946917286467, 921.4056933277998, 926.1201858234704, 3552.4493703476, 3757.8496860125288, 3778.4899038629983, 3831.6168383162017, 3859.864904728321, 9028.36801423894, 9357.0, 9480.762980925394, 9484.383515071499, 9485.66800161991, 9262.120098590623, 9278.907210927804, 9297.442153470813, 9306.90693093074, 9341.252699784001, 7198.676059577227, 7277.38142257914, 7433.888491672629, 7448.329650331604, 7451.888221676679, 5393.164059570773, 5421.484958928895, 5424.83725488236, 5570.5543556516195, 5636.85907852307, 8755.781105472413, 8954.955225223832, 9040.593173307085, 9066.818663626822, 9081.00108988024, 8793.780155496164, 9046.819063618823, 9051.230645395215, 9069.501177435304, 9148.725538233864, 5713.542916566582, 5964.373740757332, 5970.671613061149, 6071.51427885777, 6129.693515324305, 7222.0611320528, 7229.963850180716, 7268.27317268269, 7289.453291003302, 7301.196868344584, 6553.344665533408, 6636.601803891782, 6673.9666301668185, 6691.397774200345, 6806.625635589892, 6628.469722422291, 6634.734610615521, 6663.500237482305, 6667.633280169637, 6777.762778302736, 6446.9032964506505, 6501.2198536175965, 6514.3811337922825, 6548.116004339353, 6564.770233041821, 4691.577758001797, 4784.856454306377, 4846.539578740014, 4944.8763780905765, 4962.851114466427, 4659.254519276899, 4974.651774375759, 4995.700257984533, 5053.469385714594, 5065.733566608348], "value": 4156.288050960353, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.95360742281235, 293.22149692566234, 295.9319356547994, 298.34662090022834, 299.2056091078188, 4871.951280487194, 4882.804687812492, 4919.11455937931, 4929.309896614477, 4945.406551213855, 4725.905481890369, 4745.90508189836, 4745.928811067828, 4799.616030717543, 4829.661923665345, 3628.274345130978, 3738.3831667774784, 3789.2800367930145, 3861.864834730785, 3866.845326186963, 3792.4880141180865, 3828.712846536507, 3830.291396091718, 3845.1348446599573, 3855.691544676419, 3606.2607165531135, 3804.1060350817534, 3815.351390263662, 3816.9809150954206, 3821.5414150301863, 3658.652428019347, 3804.3342415077527, 3815.3895376739865, 3820.3639488752674, 3851.826667799956, 3380.3070370573973, 3383.4924761285756, 3383.5601371821504, 3405.3700065487965, 3406.9097888675633, 4571.817127314904, 4603.4808513190565, 4606.953930460686, 4616.953830461686, 4620.283856002291, 4244.554321796198, 4279.636230920375, 4317.784110794448, 4346.8913277168, 4349.0, 4992.176290911995, 5002.324686167357, 5017.49825017499, 5023.849284521433, 5036.622253330978, 3671.2473942841652, 3680.834362453696, 3720.3489389356755, 3722.678449150567, 3732.6267373262726, 4985.35190425246, 5266.92099618506, 5270.7350235943095, 5271.762770675329, 5320.0, 3670.724695647837, 3683.4106542953537, 3684.8157592120288, 3695.852165913387, 3701.815419065873, 3723.2739615774663, 3730.8734021607424, 3734.10381508436, 3741.0, 3747.913105199462, 4279.101388708344, 4280.614744672995, 4288.185244803477, 4306.20335237982, 4307.612314891675, 4791.448983366888, 5046.369203849519, 5052.823151189691, 5054.519820617035, 5065.201853342084, 1277.329402063924, 1289.4262053386153, 1294.1199984010964, 1307.5750381126172, 1309.9476020959055, 840.1472505407016, 845.2139510255521, 858.4891989266376, 858.6651206029653, 880.3793325705362, 4396.252637051709, 4433.534478879736, 4439.822407103679, 4457.977710111429, 4502.594766471033, 5123.2827404163445, 5150.124478838607, 5161.690298582097, 5165.793368265227, 5188.714620695898, 4365.301551751704, 4424.6681498887065, 4483.50681425043, 4509.864704058883, 4514.68397212192, 2722.4282900591097, 2735.39821239327, 2749.3676454415595, 2754.0, 2758.5724212747054, 4860.659753817199, 4864.610831133501, 4865.318855360252, 4888.633352498577, 4903.313536104948, 5004.647815526697, 5197.8440646780655, 5239.921401178985, 5246.979912733625, 5302.840914772563, 4781.784819683053, 4802.375691160197, 4819.349387832598, 4874.805007799649, 4886.462489126193, 5132.79468821243, 5136.922946155885, 5158.819441319536, 5172.482751724948, 5186.118359878754, 4924.729139897197, 5031.52200540948, 5050.9747451263975, 5074.0, 5082.186850103965, 4811.855644330676, 4822.228443449171, 4827.541383568555, 4861.0, 4885.2427873679635, 3636.6908812751212, 3659.670629643345, 3673.7039350406, 3685.74199806011, 3694.2796154749567, 4931.3589233399425, 5059.620528460307, 5141.717205553581, 5150.536451719363, 5162.7351298932, 4916.975415122902, 4927.605791536596, 4951.306817045544, 4960.851174464772, 4980.103581355246, 4921.237208232729, 4944.121233931083, 4945.431275403231, 4995.850124496271, 4995.925061124013, 4733.881652958704, 4843.515648435128, 4849.830255941025, 4894.769452161557, 4898.363212782316, 4853.781579828987, 4959.528844759742, 4994.625403094855, 5088.313077734459, 5124.923766009179, 4913.926291105565, 4954.628402869871, 4980.551750342486, 4989.55094041538, 4998.075356059141], "value": 3798.3447324816057, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.0592857749089, 641.6022066318883, 747.7906186267846, 768.642581199742, 791.1416113516838, 12540.122191446591, 12585.370731463436, 12591.118621696485, 12834.229946203222, 13320.866791332084, 12303.077269204801, 12334.383280835968, 12343.0, 12361.38193090344, 12361.876381236185, 620.426105852085, 758.7534051433306, 773.0954782904001, 794.6463823598501, 796.6853093028251, 786.0842118931438, 793.4604468961104, 795.7294519863245, 795.8010497375656, 796.6892911764417, 846.8687353460224, 849.049065047145, 849.5030407211789, 849.762066621347, 850.0224741547239, 646.7833275852571, 812.7277362083709, 829.17911267845, 834.5910503853129, 836.0427310729181, 600.3606159440226, 603.2369053147728, 605.4429924469499, 609.1076572820839, 613.5735663713721, 12799.616011519669, 12820.743585128339, 12844.165129266568, 12859.807102893465, 12875.935620321932, 12227.327496987662, 12327.198732082476, 12347.876521234763, 12568.434420451102, 12724.1729287596, 10583.788324233472, 10822.783544329148, 10849.566017359297, 10962.177836662202, 10978.0, 3935.980320098381, 3936.5866584008554, 3955.3473676843287, 3966.504186976628, 4001.099752555681, 44912.550874491164, 45717.54282457166, 46401.0, 46425.0, 46653.5334646656, 9619.326647134634, 9621.615135394646, 9986.10125088731, 10067.345622534658, 10072.74818129538, 17854.375096871547, 17960.55098622519, 17984.730229046574, 18010.63978720418, 18038.0, 6483.837904052436, 6501.252355979028, 6516.902246466307, 6549.705263263165, 6737.629430381376, 3542.132177616449, 3595.4606808978338, 3630.7276954228537, 3642.0894776305922, 3684.2263124744104, 2590.6114082887716, 2670.5593577059567, 2673.2381271337576, 2675.7324267573476, 2677.6251324814543, 459.19640628899424, 489.3833769450518, 490.4874406245482, 498.1730327656146, 509.320057722944, 450.23909592788084, 511.8976204759063, 554.2545276602963, 566.5863919338823, 590.1221266143109, 7755.72854950074, 7888.881666775003, 7904.446688731849, 8002.5598592077995, 8054.637541310655, 543.0890427309618, 545.4191286280098, 576.6338375131821, 592.6947621974687, 593.6260156101629, 3857.247836671822, 4004.139110091286, 4012.0972781124597, 4052.2705912935953, 4118.9588104119175, 58258.70870645619, 62464.18911149009, 62838.0, 63064.684676576326, 63086.68456657687, 48596.27105593419, 54259.72870135624, 54355.0, 55366.16950745742, 56913.71543142258, 47815.282770758466, 48331.27503087457, 48586.27120593194, 49030.50969490331, 49113.50886491161, 6806.9319306808275, 7067.399271062018, 7165.964170179116, 7205.207427183111, 8617.353698472514, 30916.381672366875, 31580.842095789372, 32059.0, 32115.51826722648, 32733.34533309273, 40762.1847563053, 41431.1713765729, 41467.7926610365, 41982.790086049376, 42520.5747942529, 8796.516191609522, 8917.777055573662, 8974.282057435521, 9088.227500662253, 9179.954100229455, 9953.253505986935, 10086.798264034825, 10124.240681948737, 10274.383536987805, 10337.17302615804, 8552.828943421222, 9315.34792564514, 9895.604175832887, 10016.799664006534, 10957.56169753201, 11213.551457941918, 11260.549578016788, 11268.211225214483, 11415.771684566429, 11707.180497365103, 10274.229432792421, 10287.948560257151, 10509.369437833755, 11116.833247501132, 11371.0, 9078.81842363136, 9138.68014619485, 9449.48027858447, 9721.368111072758, 10086.0, 9653.0, 9774.951125244328, 9916.801663966824, 9949.303548751517, 10094.899051009395], "value": 5945.918820762472, "unit": "score"}], "name": "dromaeo_css", "value": 6385.280532136692, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 114.0, 115.0, 115.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 136.0, 158.0, 214.0, 130.0, 157.0, 155.0, 136.0, 189.0, 134.0], "value": 156.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 160.0, 162.0, 162.0, 162.0, 163.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 153.0, 75.0, 76.0, 76.0, 75.0, 123.0, 76.0], "value": 88.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 151.0, 93.0, 93.0, 94.0, 126.0, 95.0, 94.0, 128.0], "value": 106.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 64.0, 69.0, 64.0, 63.0, 63.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 70.0, 65.0, 66.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 88.0, 81.0, 84.0, 81.0, 82.0, 83.0, 146.0, 82.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 134.0, 140.0, 131.0, 130.0, 137.0, 128.0, 128.0, 156.0, 137.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 176.0, 170.0, 170.0, 172.0, 180.0, 174.0, 173.0, 173.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 66.0, 67.0, 66.0, 64.0, 66.0, 66.0, 64.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1577.5, "alertThreshold": 2.0}]} 21:34:12 INFO - Return code: 0 21:34:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:34:12 ERROR - # TBPL SUCCESS # 21:34:12 INFO - Running post-action listener: _resource_record_post_action 21:34:12 INFO - ##### 21:34:12 INFO - ##### Finished run-tests step (success) 21:34:12 INFO - ##### 21:34:12 INFO - Running post-run listener: _resource_record_post_run 21:34:12 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 533757952; Read time: 12; Write time: 401800 21:34:12 INFO - TinderboxPrint: CPU usage
13.1% 21:34:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 21:34:12 INFO - TinderboxPrint: I/O write bytes / time
533,757,952 / 401,800 21:34:12 INFO - TinderboxPrint: CPU idle
8,330.4 (86.9%) 21:34:12 INFO - TinderboxPrint: CPU user
1,213.8 (12.7%) 21:34:12 INFO - TinderboxPrint: Swap in / out
0 / 0 21:34:12 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236785664; Read time: 0; Write time: 190200 21:34:12 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296972288; Read time: 12; Write time: 211600 21:34:13 INFO - Running post-run listener: _upload_blobber_files 21:34:13 INFO - Blob upload gear active. 21:34:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:34:13 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 21:34:13 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'] 21:34:13 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 21:34:13 INFO - (blobuploader) - INFO - Open directory for files ... 21:34:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:34:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:34:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:34:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:34:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:34:14 INFO - (blobuploader) - INFO - Done attempting. 21:34:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:34:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:34:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:34:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:34:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:34:15 INFO - (blobuploader) - INFO - Done attempting. 21:34:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:34:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:34:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:34:20 INFO - (blobuploader) - INFO - Done attempting. 21:34:20 INFO - (blobuploader) - INFO - Iteration through files over. 21:34:20 INFO - Return code: 0 21:34:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:34:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:34:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79f92179f2bfe0329875ba9bd4c1707a24f05eae9ddac66351f27bcfa0710e3fbcb3850f151ba39c2836934af8d0f4562cb41072308a72d3ff8864d8eb956b0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6544767340e97454784f83cea654cbe6a0d54f593a4a52c8781f388f677a451be328bdd20db377b86702904ff120ceeb7190b6b271b89f03c11fc0b5ed8b606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1835f2dc4f808c682bfd1a6b6727c562a4e518db7a0278318af82987cd8bf0b03a0828d2e5096699bd6301182843f5180c9b0234ee987483c1c41fb5a004c9b8"} 21:34:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:34:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:34:20 INFO - Contents: 21:34:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/79f92179f2bfe0329875ba9bd4c1707a24f05eae9ddac66351f27bcfa0710e3fbcb3850f151ba39c2836934af8d0f4562cb41072308a72d3ff8864d8eb956b0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6544767340e97454784f83cea654cbe6a0d54f593a4a52c8781f388f677a451be328bdd20db377b86702904ff120ceeb7190b6b271b89f03c11fc0b5ed8b606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1835f2dc4f808c682bfd1a6b6727c562a4e518db7a0278318af82987cd8bf0b03a0828d2e5096699bd6301182843f5180c9b0234ee987483c1c41fb5a004c9b8"} 21:34:20 INFO - Running post-run listener: copy_logs_to_upload_dir 21:34:20 INFO - Copying logs to upload dir... 21:34:20 INFO - mkdir: /builds/slave/test/build/upload/logs 21:34:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.118274 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-19 21:34:20.419422) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 21:34:20.422954) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 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/79f92179f2bfe0329875ba9bd4c1707a24f05eae9ddac66351f27bcfa0710e3fbcb3850f151ba39c2836934af8d0f4562cb41072308a72d3ff8864d8eb956b0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6544767340e97454784f83cea654cbe6a0d54f593a4a52c8781f388f677a451be328bdd20db377b86702904ff120ceeb7190b6b271b89f03c11fc0b5ed8b606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1835f2dc4f808c682bfd1a6b6727c562a4e518db7a0278318af82987cd8bf0b03a0828d2e5096699bd6301182843f5180c9b0234ee987483c1c41fb5a004c9b8"} build_url:https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/KmiO5qaoQme2AzOiriRIVg/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/79f92179f2bfe0329875ba9bd4c1707a24f05eae9ddac66351f27bcfa0710e3fbcb3850f151ba39c2836934af8d0f4562cb41072308a72d3ff8864d8eb956b0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6544767340e97454784f83cea654cbe6a0d54f593a4a52c8781f388f677a451be328bdd20db377b86702904ff120ceeb7190b6b271b89f03c11fc0b5ed8b606", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1835f2dc4f808c682bfd1a6b6727c562a4e518db7a0278318af82987cd8bf0b03a0828d2e5096699bd6301182843f5180c9b0234ee987483c1c41fb5a004c9b8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 21:34:20.448077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:34:20.448404) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1463712968.584786-2033497026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004552 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:34:20.533262) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 21:34:20.533818) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 21:34:20.534305) ========= ========= Total master_lag: 0.17 =========