builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1463480948.1 results: success (0) buildid: 20160517021529 builduid: 1251024ad50a453ab548e66c29a31f54 revision: 92b07e6e84bf828040ac5231eafea31c843e0472 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.097617) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.098074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.098421) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.396377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.396704) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-17 03:29:08-- 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% 225M=0s 2016-05-17 03:29:08 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116641 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.536883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.537207) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017028 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:08.598062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 03:29:08.598412) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 92b07e6e84bf828040ac5231eafea31c843e0472 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 92b07e6e84bf828040ac5231eafea31c843e0472 --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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-17 03:29:08,621 truncating revision to first 12 chars 2016-05-17 03:29:08,622 Setting DEBUG logging. 2016-05-17 03:29:08,622 attempt 1/10 2016-05-17 03:29:08,622 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/92b07e6e84bf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 03:29:09,371 unpacking tar archive at: fx-team-92b07e6e84bf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.025906 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 03:29:09.649775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:09.650090) ========= script_repo_revision: 92b07e6e84bf828040ac5231eafea31c843e0472 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:09.650515) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:09.650842) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 03:29:09.664087) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-17 03:29:09.664396) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:29:09 INFO - MultiFileLogger online at 20160517 03:29:09 in /builds/slave/test 03:29:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:29:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:09 INFO - {'append_to_log': False, 03:29:09 INFO - 'base_work_dir': '/builds/slave/test', 03:29:09 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:29:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:09 INFO - 'branch': 'Fx-Team-Non-PGO', 03:29:09 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:09 INFO - 'config_files': ('talos/linux_config.py',), 03:29:09 INFO - 'default_actions': ('clobber', 03:29:09 INFO - 'read-buildbot-config', 03:29:09 INFO - 'download-and-extract', 03:29:09 INFO - 'populate-webroot', 03:29:09 INFO - 'create-virtualenv', 03:29:09 INFO - 'install', 03:29:09 INFO - 'run-tests'), 03:29:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:09 INFO - 'download_minidump_stackwalk': True, 03:29:09 INFO - 'download_symbols': 'ondemand', 03:29:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:29:09 INFO - 'tooltool.py': '/tools/tooltool.py', 03:29:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:29:09 INFO - '/tools/misc-python/virtualenv.py')}, 03:29:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:09 INFO - 'installer_path': 'installer.exe', 03:29:09 INFO - 'log_level': 'info', 03:29:09 INFO - 'log_name': 'talos', 03:29:09 INFO - 'log_to_console': True, 03:29:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:29:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:29:09 INFO - 'opt_config_files': (), 03:29:09 INFO - 'pip_index': False, 03:29:09 INFO - 'sps_profile': False, 03:29:09 INFO - 'sps_profile_interval': 0, 03:29:09 INFO - 'suite': 'dromaeojs', 03:29:09 INFO - 'system_bits': '32', 03:29:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:09 INFO - 'title': 'talos-linux64-ix-022', 03:29:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:09 INFO - 'use_talos_json': True, 03:29:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:09 INFO - 'work_dir': 'build'} 03:29:09 INFO - ##### 03:29:09 INFO - ##### Running clobber step. 03:29:09 INFO - ##### 03:29:09 INFO - Running pre-action listener: _resource_record_pre_action 03:29:09 INFO - Running main action method: clobber 03:29:09 INFO - rmtree: /builds/slave/test/build 03:29:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:10 INFO - Running post-action listener: _resource_record_post_action 03:29:10 INFO - ##### 03:29:10 INFO - ##### Finished clobber step (success) 03:29:10 INFO - ##### 03:29:10 INFO - ##### 03:29:10 INFO - ##### Running read-buildbot-config step. 03:29:10 INFO - ##### 03:29:10 INFO - Running pre-action listener: _resource_record_pre_action 03:29:10 INFO - Running main action method: read_buildbot_config 03:29:10 INFO - Using buildbot properties: 03:29:10 INFO - { 03:29:10 INFO - "project": "", 03:29:10 INFO - "product": "firefox", 03:29:10 INFO - "script_repo_revision": "production", 03:29:10 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:29:10 INFO - "repository": "", 03:29:10 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 03:29:10 INFO - "buildid": "20160517021529", 03:29:10 INFO - "pgo_build": "False", 03:29:10 INFO - "basedir": "/builds/slave/test", 03:29:10 INFO - "buildnumber": 1753, 03:29:10 INFO - "slavename": "talos-linux64-ix-022", 03:29:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:29:10 INFO - "platform": "ubuntu64_hw", 03:29:10 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:29:10 INFO - "branch": "fx-team", 03:29:10 INFO - "repo_path": "integration/fx-team", 03:29:10 INFO - "revision": "92b07e6e84bf828040ac5231eafea31c843e0472", 03:29:10 INFO - "stage_platform": "linux64", 03:29:10 INFO - "builduid": "1251024ad50a453ab548e66c29a31f54", 03:29:10 INFO - "slavebuilddir": "test" 03:29:10 INFO - } 03:29:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 03:29:10 INFO - Running post-action listener: _resource_record_post_action 03:29:10 INFO - ##### 03:29:10 INFO - ##### Finished read-buildbot-config step (success) 03:29:10 INFO - ##### 03:29:10 INFO - ##### 03:29:10 INFO - ##### Running download-and-extract step. 03:29:10 INFO - ##### 03:29:10 INFO - Running pre-action listener: _resource_record_pre_action 03:29:10 INFO - Running main action method: download_and_extract 03:29:10 INFO - mkdir: /builds/slave/test/build/tests 03:29:10 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')]} 03:29:10 INFO - https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:29:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:29:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:29:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:13 INFO - Downloaded 1361 bytes. 03:29:13 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 03:29:13 INFO - Using the following test package requirements: 03:29:13 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 03:29:13 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:29:13 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:29:13 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'jsshell-linux-x86_64.zip'], 03:29:13 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:29:13 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 03:29:13 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:29:13 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:29:13 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:29:13 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 03:29:13 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:29:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:29:13 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')]} 03:29:13 INFO - https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:29:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:29:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:29:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:16 INFO - Downloaded 22448136 bytes. 03:29:16 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'] 03:29:16 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 03:29:17 INFO - Return code: 0 03:29:17 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 03:29:17 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')]} 03:29:17 INFO - https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:29:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:29:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 03:29:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:18 INFO - Downloaded 22448136 bytes. 03:29:18 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'] 03:29:18 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 03:29:20 INFO - Return code: 0 03:29: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')]} 03:29:20 INFO - https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:29:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 03:29:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 03:29:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:21 INFO - Downloaded 10840194 bytes. 03:29:21 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'] 03:29:21 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 03:29:21 INFO - Return code: 0 03:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:21 INFO - https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JughdRDHRw-lgAF18dL1Rg/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 03:29:23 INFO - Downloaded 54767280 bytes. 03:29:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:29:23 INFO - mkdir: /builds/slave/test/properties 03:29:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:23 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:23 INFO - Contents: 03:29:23 INFO - build_url:https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 03:29:23 INFO - Running post-action listener: _resource_record_post_action 03:29:23 INFO - Running post-action listener: set_extra_try_arguments 03:29:23 INFO - ##### 03:29:23 INFO - ##### Finished download-and-extract step (success) 03:29:23 INFO - ##### 03:29:23 INFO - ##### 03:29:23 INFO - ##### Running populate-webroot step. 03:29:23 INFO - ##### 03:29:23 INFO - Running pre-action listener: _resource_record_pre_action 03:29:23 INFO - Running main action method: populate_webroot 03:29:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:29:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:29:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:29:23 INFO - '--tppagecycles', 03:29:23 INFO - '7'], 03:29:23 INFO - 'tests': ['tsvgm']}}, 03:29:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:29:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:29:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:29:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:29:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['damp', 'tps']}, 03:29:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'tests': ['damp', 'tps']}, 03:29:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['dromaeo_dom']}, 03:29:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'other-e10s': {'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['a11yr', 03:29:23 INFO - 'ts_paint', 03:29:23 INFO - 'tpaint', 03:29:23 INFO - 'sessionrestore', 03:29:23 INFO - 'sessionrestore_no_auto_restore', 03:29:23 INFO - 'tabpaint']}, 03:29:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['tsvgx', 03:29:23 INFO - 'tsvgr_opacity', 03:29:23 INFO - 'tart', 03:29:23 INFO - 'tscrollx', 03:29:23 INFO - 'cart']}, 03:29:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:29:23 INFO - 'tsvgr_opacity', 03:29:23 INFO - 'tart', 03:29:23 INFO - 'tscrollx', 03:29:23 INFO - 'cart']}, 03:29:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'talos_options': ['--disable-e10s'], 03:29:23 INFO - 'tests': ['tp5o']}, 03:29:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'tests': ['tp5o']}, 03:29:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'talos_options': ['--disable-e10s', 03:29:23 INFO - '--xperf_path', 03:29:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:23 INFO - 'tests': ['tp5n']}, 03:29:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:29:23 INFO - 'talos_options': ['--xperf_path', 03:29:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:23 INFO - 'tests': ['tp5n']}}, 03:29:23 INFO - 'talos.zip': {'path': '', 03:29:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:29:23 INFO - Running post-action listener: _resource_record_post_action 03:29:23 INFO - ##### 03:29:23 INFO - ##### Finished populate-webroot step (success) 03:29:23 INFO - ##### 03:29:23 INFO - ##### 03:29:23 INFO - ##### Running create-virtualenv step. 03:29:23 INFO - ##### 03:29:23 INFO - Running pre-action listener: _resource_record_pre_action 03:29:23 INFO - Running main action method: create_virtualenv 03:29:23 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:23 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 03:29:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:29:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:24 INFO - Using real prefix '/usr' 03:29:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:24 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:26 INFO - Installing pip.................done. 03:29:26 INFO - Return code: 0 03:29:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29:26 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')]} 03:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:26 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')]} 03:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:26 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:26 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 03:29:26 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 03:29:26 INFO - Using env: {'DISPLAY': ':0', 03:29:26 INFO - 'HOME': '/home/cltbld', 03:29:26 INFO - 'LANG': 'en_US.UTF-8', 03:29:26 INFO - 'LANGUAGE': 'en_US:en', 03:29:26 INFO - 'LOGNAME': 'cltbld', 03:29:26 INFO - 'MAIL': '/var/mail/cltbld', 03:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:26 INFO - 'MOZ_NO_REMOTE': '1', 03:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:26 INFO - 'NO_EM_RESTART': '1', 03:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:26 INFO - 'PWD': '/builds/slave/test', 03:29:26 INFO - 'SHELL': '/bin/bash', 03:29:26 INFO - 'SHLVL': '1', 03:29:26 INFO - 'TERM': 'linux', 03:29:26 INFO - 'TMOUT': '86400', 03:29:26 INFO - 'USER': 'cltbld', 03:29:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:26 INFO - '_': '/tools/buildbot/bin/python'} 03:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:26 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 03:29:26 INFO - Cleaning up... 03:29:26 INFO - Return code: 0 03:29:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:29:26 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')]} 03:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:26 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')]} 03:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:26 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:26 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 03:29:26 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 03:29:26 INFO - Using env: {'DISPLAY': ':0', 03:29:26 INFO - 'HOME': '/home/cltbld', 03:29:26 INFO - 'LANG': 'en_US.UTF-8', 03:29:26 INFO - 'LANGUAGE': 'en_US:en', 03:29:26 INFO - 'LOGNAME': 'cltbld', 03:29:26 INFO - 'MAIL': '/var/mail/cltbld', 03:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:26 INFO - 'MOZ_NO_REMOTE': '1', 03:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:26 INFO - 'NO_EM_RESTART': '1', 03:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:26 INFO - 'PWD': '/builds/slave/test', 03:29:26 INFO - 'SHELL': '/bin/bash', 03:29:26 INFO - 'SHLVL': '1', 03:29:26 INFO - 'TERM': 'linux', 03:29:26 INFO - 'TMOUT': '86400', 03:29:26 INFO - 'USER': 'cltbld', 03:29:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:26 INFO - '_': '/tools/buildbot/bin/python'} 03:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:27 INFO - Downloading/unpacking psutil>=3.1.1 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:27 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 03:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:29 INFO - Installing collected packages: psutil 03:29:29 INFO - Running setup.py install for psutil 03:29:29 INFO - building 'psutil._psutil_linux' extension 03:29:29 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 03:29:29 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 03:29:29 INFO - building 'psutil._psutil_posix' extension 03:29:29 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 03:29:29 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 03:29:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:29 INFO - Successfully installed psutil 03:29:29 INFO - Cleaning up... 03:29:29 INFO - Return code: 0 03:29:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:29:29 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')]} 03:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:29 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')]} 03:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:29 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:29 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 03:29:29 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 03:29:29 INFO - Using env: {'DISPLAY': ':0', 03:29:29 INFO - 'HOME': '/home/cltbld', 03:29:29 INFO - 'LANG': 'en_US.UTF-8', 03:29:29 INFO - 'LANGUAGE': 'en_US:en', 03:29:29 INFO - 'LOGNAME': 'cltbld', 03:29:29 INFO - 'MAIL': '/var/mail/cltbld', 03:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:29 INFO - 'MOZ_NO_REMOTE': '1', 03:29:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:29 INFO - 'NO_EM_RESTART': '1', 03:29:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:29 INFO - 'PWD': '/builds/slave/test', 03:29:29 INFO - 'SHELL': '/bin/bash', 03:29:29 INFO - 'SHLVL': '1', 03:29:29 INFO - 'TERM': 'linux', 03:29:29 INFO - 'TMOUT': '86400', 03:29:29 INFO - 'USER': 'cltbld', 03:29:29 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:29 INFO - '_': '/tools/buildbot/bin/python'} 03:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:29:29 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 03:29:29 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 03:29:29 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 03:29:29 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 03:29:29 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 03:29:29 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 03:29:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:29:31 INFO - Installing collected packages: mozsystemmonitor 03:29:31 INFO - Running setup.py install for mozsystemmonitor 03:29:31 INFO - Successfully installed mozsystemmonitor 03:29:31 INFO - Cleaning up... 03:29:31 INFO - Return code: 0 03:29:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:29:31 INFO - Using env: {'DISPLAY': ':0', 03:29:31 INFO - 'HOME': '/home/cltbld', 03:29:31 INFO - 'LANG': 'en_US.UTF-8', 03:29:31 INFO - 'LANGUAGE': 'en_US:en', 03:29:31 INFO - 'LOGNAME': 'cltbld', 03:29:31 INFO - 'MAIL': '/var/mail/cltbld', 03:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:31 INFO - 'MOZ_NO_REMOTE': '1', 03:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:31 INFO - 'NO_EM_RESTART': '1', 03:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:31 INFO - 'PWD': '/builds/slave/test', 03:29:31 INFO - 'SHELL': '/bin/bash', 03:29:31 INFO - 'SHLVL': '1', 03:29:31 INFO - 'TERM': 'linux', 03:29:31 INFO - 'TMOUT': '86400', 03:29:31 INFO - 'USER': 'cltbld', 03:29:31 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:31 INFO - '_': '/tools/buildbot/bin/python'} 03:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:32 INFO - Downloading/unpacking blobuploader==1.2.4 03:29:32 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 03:29:32 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 03:29:32 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 03:29:32 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 03:29:32 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 03:29:32 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 03:29:33 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 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 03:29:34 INFO - Downloading docopt-0.6.1.tar.gz 03:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:34 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:34 INFO - Running setup.py install for blobuploader 03:29:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:29:34 INFO - Running setup.py install for requests 03:29:35 INFO - Running setup.py install for docopt 03:29:35 INFO - Successfully installed blobuploader requests docopt 03:29:35 INFO - Cleaning up... 03:29:35 INFO - Return code: 0 03:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:35 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')]} 03:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:35 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')]} 03:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:35 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:35 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 03:29:35 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 03:29:35 INFO - Using env: {'DISPLAY': ':0', 03:29:35 INFO - 'HOME': '/home/cltbld', 03:29:35 INFO - 'LANG': 'en_US.UTF-8', 03:29:35 INFO - 'LANGUAGE': 'en_US:en', 03:29:35 INFO - 'LOGNAME': 'cltbld', 03:29:35 INFO - 'MAIL': '/var/mail/cltbld', 03:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:35 INFO - 'MOZ_NO_REMOTE': '1', 03:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:35 INFO - 'NO_EM_RESTART': '1', 03:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:35 INFO - 'PWD': '/builds/slave/test', 03:29:35 INFO - 'SHELL': '/bin/bash', 03:29:35 INFO - 'SHLVL': '1', 03:29:35 INFO - 'TERM': 'linux', 03:29:35 INFO - 'TMOUT': '86400', 03:29:35 INFO - 'USER': 'cltbld', 03:29:35 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:35 INFO - '_': '/tools/buildbot/bin/python'} 03:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:35 INFO - Running setup.py (path:/tmp/pip-ASbBMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:35 INFO - Running setup.py (path:/tmp/pip-_F8JrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:35 INFO - Running setup.py (path:/tmp/pip-pYUHVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:35 INFO - Running setup.py (path:/tmp/pip-1HRJgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:35 INFO - Running setup.py (path:/tmp/pip-uYgcRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:35 INFO - Running setup.py (path:/tmp/pip-tfdpX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:35 INFO - Running setup.py (path:/tmp/pip-UELLZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:36 INFO - Running setup.py (path:/tmp/pip-k9wosv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:36 INFO - Running setup.py (path:/tmp/pip-agVfOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:36 INFO - Running setup.py (path:/tmp/pip-8ACVk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:36 INFO - Running setup.py (path:/tmp/pip-Iaiq2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:36 INFO - Running setup.py (path:/tmp/pip-h0mG5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:36 INFO - Running setup.py (path:/tmp/pip-xqPNKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:36 INFO - Running setup.py (path:/tmp/pip-XVMBvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:36 INFO - Running setup.py (path:/tmp/pip-RcZYL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:36 INFO - Running setup.py (path:/tmp/pip-TFcB9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:36 INFO - Running setup.py (path:/tmp/pip-HXhbVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:36 INFO - Running setup.py install for manifestparser 03:29:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:29:36 INFO - Running setup.py install for mozcrash 03:29:36 INFO - Running setup.py install for mozdebug 03:29:37 INFO - Running setup.py install for mozdevice 03:29:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for mozfile 03:29:37 INFO - Running setup.py install for mozhttpd 03:29:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for mozinfo 03:29:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for mozInstall 03:29:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for mozleak 03:29:37 INFO - Running setup.py install for mozlog 03:29:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for moznetwork 03:29:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:29:37 INFO - Running setup.py install for mozprocess 03:29:38 INFO - Running setup.py install for mozprofile 03:29:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:29:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:29:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:29:38 INFO - Running setup.py install for mozrunner 03:29:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:29:38 INFO - Running setup.py install for mozscreenshot 03:29:38 INFO - Running setup.py install for moztest 03:29:38 INFO - Running setup.py install for mozversion 03:29:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:29:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:38 INFO - Cleaning up... 03:29:38 INFO - Return code: 0 03:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:38 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')]} 03:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:38 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')]} 03:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:38 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:38 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 03:29:38 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 03:29:38 INFO - Using env: {'DISPLAY': ':0', 03:29:38 INFO - 'HOME': '/home/cltbld', 03:29:38 INFO - 'LANG': 'en_US.UTF-8', 03:29:38 INFO - 'LANGUAGE': 'en_US:en', 03:29:38 INFO - 'LOGNAME': 'cltbld', 03:29:38 INFO - 'MAIL': '/var/mail/cltbld', 03:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:38 INFO - 'MOZ_NO_REMOTE': '1', 03:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:38 INFO - 'NO_EM_RESTART': '1', 03:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:38 INFO - 'PWD': '/builds/slave/test', 03:29:38 INFO - 'SHELL': '/bin/bash', 03:29:38 INFO - 'SHLVL': '1', 03:29:38 INFO - 'TERM': 'linux', 03:29:38 INFO - 'TMOUT': '86400', 03:29:38 INFO - 'USER': 'cltbld', 03:29:38 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:38 INFO - '_': '/tools/buildbot/bin/python'} 03:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:38 INFO - Running setup.py (path:/tmp/pip-bcejLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:38 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)) 03:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:38 INFO - Running setup.py (path:/tmp/pip-cRSQED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:38 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)) 03:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:38 INFO - Running setup.py (path:/tmp/pip-BEmxep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:39 INFO - Running setup.py (path:/tmp/pip-NilM6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:39 INFO - Running setup.py (path:/tmp/pip-NrPz_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:39 INFO - Running setup.py (path:/tmp/pip-z2dkM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:39 INFO - Running setup.py (path:/tmp/pip-xWPshb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:39 INFO - Running setup.py (path:/tmp/pip-zPdH_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:39 INFO - Running setup.py (path:/tmp/pip-ZUE4Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:39 INFO - Running setup.py (path:/tmp/pip-rJQkoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:39 INFO - Running setup.py (path:/tmp/pip-qWeeJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:39 INFO - Running setup.py (path:/tmp/pip-oYgHUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:39 INFO - Running setup.py (path:/tmp/pip-G3gw7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:39 INFO - Running setup.py (path:/tmp/pip-G8Nf5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:39 INFO - Running setup.py (path:/tmp/pip-o2R6tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:39 INFO - Running setup.py (path:/tmp/pip-t1D80W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:39 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)) 03:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:39 INFO - Running setup.py (path:/tmp/pip-BY1uyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:40 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)) 03:29:40 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)) 03:29:40 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)) 03:29:40 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)) 03:29:40 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)) 03:29:40 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)) 03:29:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:29:40 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 03:29:40 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 03:29:40 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 03:29:40 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 03:29:40 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 03:29:40 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 03:29:41 INFO - Downloading blessings-1.6.tar.gz 03:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:29:41 INFO - Installing collected packages: blessings 03:29:41 INFO - Running setup.py install for blessings 03:29:42 INFO - Successfully installed blessings 03:29:42 INFO - Cleaning up... 03:29:42 INFO - Return code: 0 03:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:29:42 INFO - Reading from file tmpfile_stdout 03:29:42 INFO - Current package versions: 03:29:42 INFO - argparse == 1.2.1 03:29:42 INFO - blessings == 1.6 03:29:42 INFO - blobuploader == 1.2.4 03:29:42 INFO - docopt == 0.6.1 03:29:42 INFO - manifestparser == 1.1 03:29:42 INFO - mozInstall == 1.12 03:29:42 INFO - mozcrash == 0.17 03:29:42 INFO - mozdebug == 0.1 03:29:42 INFO - mozdevice == 0.48 03:29:42 INFO - mozfile == 1.2 03:29:42 INFO - mozhttpd == 0.7 03:29:42 INFO - mozinfo == 0.9 03:29:42 INFO - mozleak == 0.1 03:29:42 INFO - mozlog == 3.2 03:29:42 INFO - moznetwork == 0.27 03:29:42 INFO - mozprocess == 0.22 03:29:42 INFO - mozprofile == 0.28 03:29:42 INFO - mozrunner == 6.11 03:29:42 INFO - mozscreenshot == 0.1 03:29:42 INFO - mozsystemmonitor == 0.3 03:29:42 INFO - moztest == 0.7 03:29:42 INFO - mozversion == 1.4 03:29:42 INFO - psutil == 3.1.1 03:29:42 INFO - requests == 1.2.3 03:29:42 INFO - wsgiref == 0.1.2 03:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:42 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:42 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 03:29:42 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 03:29:42 INFO - Using env: {'DISPLAY': ':0', 03:29:42 INFO - 'HOME': '/home/cltbld', 03:29:42 INFO - 'LANG': 'en_US.UTF-8', 03:29:42 INFO - 'LANGUAGE': 'en_US:en', 03:29:42 INFO - 'LOGNAME': 'cltbld', 03:29:42 INFO - 'MAIL': '/var/mail/cltbld', 03:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:42 INFO - 'MOZ_NO_REMOTE': '1', 03:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:42 INFO - 'NO_EM_RESTART': '1', 03:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:42 INFO - 'PWD': '/builds/slave/test', 03:29:42 INFO - 'SHELL': '/bin/bash', 03:29:42 INFO - 'SHLVL': '1', 03:29:42 INFO - 'TERM': 'linux', 03:29:42 INFO - 'TMOUT': '86400', 03:29:42 INFO - 'USER': 'cltbld', 03:29:42 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:42 INFO - '_': '/tools/buildbot/bin/python'} 03:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 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)) 03:29:42 INFO - Cleaning up... 03:29:42 INFO - Return code: 0 03:29:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29: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')]} 03:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:42 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:42 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 03:29:42 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 03:29:42 INFO - Using env: {'DISPLAY': ':0', 03:29:42 INFO - 'HOME': '/home/cltbld', 03:29:42 INFO - 'LANG': 'en_US.UTF-8', 03:29:42 INFO - 'LANGUAGE': 'en_US:en', 03:29:42 INFO - 'LOGNAME': 'cltbld', 03:29:42 INFO - 'MAIL': '/var/mail/cltbld', 03:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:42 INFO - 'MOZ_NO_REMOTE': '1', 03:29:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:42 INFO - 'NO_EM_RESTART': '1', 03:29:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:42 INFO - 'PWD': '/builds/slave/test', 03:29:42 INFO - 'SHELL': '/bin/bash', 03:29:42 INFO - 'SHLVL': '1', 03:29:42 INFO - 'TERM': 'linux', 03:29:42 INFO - 'TMOUT': '86400', 03:29:42 INFO - 'USER': 'cltbld', 03:29:42 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:42 INFO - '_': '/tools/buildbot/bin/python'} 03:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:42 INFO - Downloading/unpacking jsonschema 03:29:42 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 03:29:42 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 03:29:42 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 03:29:42 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 03:29:42 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 03:29:42 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 03:29:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:45 INFO - Downloading/unpacking functools32 (from jsonschema) 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:45 INFO - warning: no files found matching '*.txt' 03:29:45 INFO - no previously-included directories found matching 'build' 03:29:45 INFO - no previously-included directories found matching 'dist' 03:29:45 INFO - no previously-included directories found matching '.git*' 03:29:45 INFO - Installing collected packages: jsonschema, functools32 03:29:45 INFO - Running setup.py install for functools32 03:29:45 INFO - warning: no files found matching '*.txt' 03:29:45 INFO - no previously-included directories found matching 'build' 03:29:45 INFO - no previously-included directories found matching 'dist' 03:29:45 INFO - no previously-included directories found matching '.git*' 03:29:45 INFO - Successfully installed jsonschema functools32 03:29:45 INFO - Cleaning up... 03:29:45 INFO - Return code: 0 03:29:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:29:45 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')]} 03:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:45 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')]} 03:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:29:45 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 0x7f766bd75f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1924770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', '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 03:29:45 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 03:29:45 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 03:29:45 INFO - Using env: {'DISPLAY': ':0', 03:29:45 INFO - 'HOME': '/home/cltbld', 03:29:45 INFO - 'LANG': 'en_US.UTF-8', 03:29:45 INFO - 'LANGUAGE': 'en_US:en', 03:29:45 INFO - 'LOGNAME': 'cltbld', 03:29:45 INFO - 'MAIL': '/var/mail/cltbld', 03:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:45 INFO - 'MOZ_NO_REMOTE': '1', 03:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:45 INFO - 'NO_EM_RESTART': '1', 03:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:45 INFO - 'PWD': '/builds/slave/test', 03:29:45 INFO - 'SHELL': '/bin/bash', 03:29:45 INFO - 'SHLVL': '1', 03:29:45 INFO - 'TERM': 'linux', 03:29:45 INFO - 'TMOUT': '86400', 03:29:45 INFO - 'USER': 'cltbld', 03:29:45 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:45 INFO - '_': '/tools/buildbot/bin/python'} 03:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:45 INFO - Downloading/unpacking flake8 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:45 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 03:29:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:29:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:29:47 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 03:29:47 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 03:29:47 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 03:29:47 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 03:29:47 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 03:29:47 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 03:29:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:29:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:29:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:48 INFO - no previously-included directories found matching 'docs/_build' 03:29:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:29:48 INFO - Running setup.py install for pep8 03:29:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:29:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:29:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:29:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:29:48 INFO - no previously-included directories found matching 'docs/_build' 03:29:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:29:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:29:48 INFO - Cleaning up... 03:29:48 INFO - Return code: 0 03:29:48 INFO - Running post-action listener: _resource_record_post_action 03:29:48 INFO - Running post-action listener: _start_resource_monitoring 03:29:48 INFO - Starting resource monitoring. 03:29:48 INFO - ##### 03:29:48 INFO - ##### Finished create-virtualenv step (success) 03:29:48 INFO - ##### 03:29:48 INFO - ##### 03:29:48 INFO - ##### Running install step. 03:29:48 INFO - ##### 03:29:48 INFO - Running pre-action listener: _resource_record_pre_action 03:29:48 INFO - Running main action method: install 03:29:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:29:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:29:49 INFO - Reading from file tmpfile_stdout 03:29:49 INFO - Detecting whether we're running mozinstall >=1.0... 03:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:29:49 INFO - Reading from file tmpfile_stdout 03:29:49 INFO - Output received: 03:29:49 INFO - Usage: mozinstall [options] installer 03:29:49 INFO - Options: 03:29:49 INFO - -h, --help show this help message and exit 03:29:49 INFO - -d DEST, --destination=DEST 03:29:49 INFO - Directory to install application into. [default: 03:29:49 INFO - "/builds/slave/test"] 03:29:49 INFO - --app=APP Application being installed. [default: firefox] 03:29:49 INFO - mkdir: /builds/slave/test/build/application 03:29:49 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'] 03:29:49 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 03:30:04 INFO - Reading from file tmpfile_stdout 03:30:04 INFO - Output received: 03:30:04 INFO - /builds/slave/test/build/application/firefox/firefox 03:30:04 INFO - Running post-action listener: _resource_record_post_action 03:30:04 INFO - ##### 03:30:04 INFO - ##### Finished install step (success) 03:30:04 INFO - ##### 03:30:04 INFO - ##### 03:30:04 INFO - ##### Running run-tests step. 03:30:04 INFO - ##### 03:30:04 INFO - Running pre-action listener: _resource_record_pre_action 03:30:04 INFO - Running main action method: run_tests 03:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:04 INFO - Python 2.7.3 03:30:04 INFO - Return code: 0 03:30:04 INFO - grabbing minidump binary from tooltool 03:30:04 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')]} 03:30:04 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 0x19b39c0>, '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 0x19b48c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b4d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:04 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 03:30:04 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 03:30:04 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 03:30:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:04 INFO - Return code: 0 03:30:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:30:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:30:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:30:05 INFO - Return code: 0 03:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 03:30:05 INFO - Using env: {'DISPLAY': ':0', 03:30:05 INFO - 'HOME': '/home/cltbld', 03:30:05 INFO - 'LANG': 'en_US.UTF-8', 03:30:05 INFO - 'LANGUAGE': 'en_US:en', 03:30:05 INFO - 'LOGNAME': 'cltbld', 03:30:05 INFO - 'MAIL': '/var/mail/cltbld', 03:30:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:05 INFO - 'MOZ_NO_REMOTE': '1', 03:30:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:05 INFO - 'NO_EM_RESTART': '1', 03:30:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:05 INFO - 'PWD': '/builds/slave/test', 03:30:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:05 INFO - 'SHELL': '/bin/bash', 03:30:05 INFO - 'SHLVL': '1', 03:30:05 INFO - 'TERM': 'linux', 03:30:05 INFO - 'TMOUT': '86400', 03:30:05 INFO - 'USER': 'cltbld', 03:30:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395', 03:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:05 INFO - '_': '/tools/buildbot/bin/python'} 03:30:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 03:30:06 INFO - mozversion application_buildid: 20160517021529 03:30:06 INFO - mozversion application_changeset: 92b07e6e84bf828040ac5231eafea31c843e0472 03:30:06 INFO - mozversion application_display_name: Nightly 03:30:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:06 INFO - mozversion application_name: Firefox 03:30:06 INFO - mozversion application_remotingname: firefox 03:30:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:30:06 INFO - mozversion application_vendor: Mozilla 03:30:06 INFO - mozversion application_version: 49.0a1 03:30:06 INFO - mozversion platform_buildid: 20160517021529 03:30:06 INFO - mozversion platform_changeset: 92b07e6e84bf828040ac5231eafea31c843e0472 03:30:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:30:06 INFO - mozversion platform_version: 49.0a1 03:30:06 INFO - using testdate: 1463481006 03:30:06 INFO - actual date: 1463481006 03:30:06 INFO - starting webserver on 'localhost:41292' 03:30:06 INFO - SUITE-START | Running 2 tests 03:30:06 INFO - TEST-START | dromaeo_css 03:30:06 INFO - operating with platform_type : linux_ 03:30:06 INFO - Initialising browser for dromaeo_css test... 03:30:06 INFO - TEST-INFO | started process 10953 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp67rkQL/profile http://localhost:41292/getInfo.html) 03:30:10 INFO - PROCESS | 10953 | __metrics Screen width/height:1600/1200 03:30:10 INFO - PROCESS | 10953 | colorDepth:24 03:30:10 INFO - PROCESS | 10953 | Browser inner width/height: 1024/697 03:30:10 INFO - PROCESS | 10953 | __metrics 03:30:11 ERROR - PROCESS | 10953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:30:12 INFO - TEST-INFO | 10953: exit 0 03:30:12 INFO - Browser initialized. 03:30:12 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:12 INFO - TEST-INFO | started process 11037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp67rkQL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:24 INFO - PROCESS | 11037 | RSS: Main: 154521600 03:30:24 INFO - PROCESS | 11037 | 03:31:15 INFO - PROCESS | 11037 | 1463481075227 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] 03:33:28 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41292/tests/dromaeo/cssquery-ext.html) 03:33:29 INFO - PROCESS | 11037 | RSS: Main: 227483648 03:33:29 INFO - PROCESS | 11037 | 03:36:32 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-ext.html (next: http://localhost:41292/tests/dromaeo/cssquery-jquery.html) 03:36:32 INFO - PROCESS | 11037 | RSS: Main: 237961216 03:36:32 INFO - PROCESS | 11037 | 03:39:34 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41292/tests/dromaeo/cssquery-mootools.html) 03:39:35 INFO - PROCESS | 11037 | RSS: Main: 243302400 03:39:35 INFO - PROCESS | 11037 | 03:42:37 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41292/tests/dromaeo/cssquery-prototype.html) 03:42:38 INFO - PROCESS | 11037 | RSS: Main: 234143744 03:42:38 INFO - PROCESS | 11037 | 03:45:41 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41292/tests/dromaeo/cssquery-yui.html) 03:45:41 INFO - PROCESS | 11037 | RSS: Main: 208838656 03:45:41 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | Cycle 1(1): loaded http://localhost:41292/tests/dromaeo/cssquery-yui.html (next: http://localhost:41292/tests/dromaeo/cssquery-dojo.html) 03:48:44 INFO - PROCESS | 11037 | RSS: Main: 254472192 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | __start_tp_report 03:48:44 INFO - PROCESS | 11037 | _x_x_mozilla_page_load 03:48:44 INFO - PROCESS | 11037 | _x_x_mozilla_page_load_details 03:48:44 INFO - PROCESS | 11037 | |i|pagename|runs| 03:48:44 INFO - PROCESS | 11037 | |0;dojo.html;2595.5198288316656;2636.419987602727;2670.5059563980667;2670.706222315545;2677.156695640873;4605.654575906804;4631.837885674002;4674.953250467494;4684.85945421637;4703.435587729473;4668.089722504113;4717.410323709536;4744.4069491313585;4749.881252968669;4755.833545825897;4328.394024836518;4329.610335069843;4417.094495628388;4447.688661793676;4502.57225563571;4423.336499525065;4426.358178064179;4463.285874260119;4465.419495465601;4492.303692927601;4229.429027081351;4280.9143817123795;4353.673474489395;4396.736195828261;4409.162259170779;4279.379489973936;4304.031592891574;4306.698531102824;4340.045190058182;4340.565943405666;4309.676774241913;4325.978370108161;4328.437303150602;4328.653707703376;4369.1480161368545;4465.754383508905;4501.842435514774;4573.382593349888;4609.3777340059;4625.768711564442;5120;5130.820421285274;5134.794608215667;5144;5147.562457191143;5111.667741596784;5112.590992720573;5124.461931497176;5142.125838607446;5169.0954083035695;4665.6967297125575;4728.88177795551;4764;4776.832810851603;4799.160146974231;5250.97374513125;5264.473552644781;5303.893922121613;5306.389765176977;5315.229291752751;5050.84847454577;5051.924221136685;5071.264666623319;5082.567981721521;5087.694738315713;4951;5017.9247311290355;5056.7471626419265;5066.316047333563;5081.491850814963;4935.358403407606;4987.725675087832;4993.85141417476;5044.344235249394;5050.989802039533;4885.193942999365;4973.875653108629;4983.426905905795;4988.775505102279;5040.36995375578;4285.678574106954;4313.935290970638;4350.847720329773;4369.6722745794195;4423.756693381831;3769.1896242308035;3797.1266608679607;3798.734088613826;3836.7889766062576;3839.6352346527037;4382.824687012548;4387.978060109743;4389.648828093745;4395.208862404797;4432.601065904084;4903.754812259373;4953.331300274489;4982.277569752366;4989.376327959005;4999;4263.552327005682;4275;4283.271843786564;4284.528701842794;4321.395004699345;4509.075639493896;4515.661325400608;4518.344839998182;4526.434195725535;4552.681312308173;4787.2101103318255;4808.2547205183255;4823.445303790109;4832.516748325139;4837.7097374157665;5080.060188865072;5087.13518701829;5093.388793344822;5095.745212739423;5145.691258524501;4620.306953956799;4709.199436095941;4807.879803004953;4821.975890120667;4823.734694591831;4918.016396720742;4954.1825598775795;4968.254761785761;4972.0801651694555;5012.273220383097;5002.399712034579;5010.223415370623;5015.398152221756;5034.0686972910125;5068.569171620454;4758.500357462555;4877.048975449895;4897.069556784199;4911.50884911506;4920.45874953762;4904.068227036852;4920.876978075434;4928.162212423825;4929.9014019720125;4967.652264341461;4850.854474365775;4861.392325959255;4891.75260308619;4956.8760780980765;4957.603391728581;4789.233722604366;4800.855974320776;4812.494688057704;4813.374261346003;5053.84838454847;4695.929561056655;4716.858494245178;4716.929246061243;4727.290906363935;4776.9761151194025 03:48:44 INFO - PROCESS | 11037 | |1;ext.html;597.4145337569182;606.426926554406;608.8143116349513;610.0574612224113;614.8800983808158;42456;43246.35130473045;43656.78171609141;43745.78127109368;43778;40735.796321018424;44388.3341749874;44414.33378499325;44454;44635;7264.891026634578;7958.880616790739;8120.9848768903885;8136.267735903767;10118.797624047515;9841.655542056029;10202.33684810485;10204.132648724868;10206.744831379237;10210.336328138685;10226.89773102267;10275.12661423776;10280.845787313196;10283.691489255334;10285.64000259991;10161.542730577105;10168.44073575953;10212.795744085151;10213.978602139801;10218.233632477593;10155.543000564918;10217.642382516577;10225.64210252645;10229.846552301722;10230.846537301946;40398.19203615941;40784.592154078375;40790.59209407897;40820;40831.387529187086;3264.3308121834966;3512.5082488451635;3562.6615471530163;3718.9442158367647;3833.367494363427;30318.84840575783;30576.847115764278;30582.541261881088;31049.37901241985;31706.682933170603;3442.862285508577;3456.395130852091;3498.930021399583;3511.1924257420683;3552.236269202108;189080;189627;190312.0484397583;190418;190504;57407;57575.13637295444;57643.71178144083;57873.421265786805;57912.710436447545;60695;61246;61321;61388.69305653443;61433.692831535554;15016.099034057774;15303.61740956485;15443.382264709511;15458.072515649097;15469.922650386676;10689.62586309491;12353.258804471761;12631.178973366701;12701.555445559547;12951.676208094685;17105.657886842193;17117.743233851503;17332.91333543324;17346.739798903025;17387.65224695499;6543.869122617616;6658.602902954933;6740.561863478858;6802.693878775467;6859.588424694534;809.4253080312926;856.4946681457969;861.642418396371;888.9555522223862;920.0575533837607;28593.57109643357;28760.712392876223;28871.85564072166;29373.559396609067;29575.408491830472;966.4732720667113;966.569876404993;994.2940512236248;997.0976266478848;1070.1492313610697;15649;15836;16021.759673604905;16070.598235044217;16460.341586336654;371119;372789;375754;376295;376416;53832.73083634557;54039.18941215885;54206.45793542014;54324.45675543273;54334.18498722522;1158.4092113022418;1244.9875501245053;1247.544646204147;1256.296473974559;1303.778357679197;27308.726912731414;30330.54504182395;30576.235594110327;30779.84610076935;31013;47522.52477475181;48171.5182848167;48424.27363589619;48457.27314090221;48493.27260091172;365213;369021;371207;372006;372816;34978.47532287068;36054.63945360618;41132.58867411288;41816;41908;44658.77670611626;44673;44996.10007799891;45033.549664502934;45967;37702.62297376991;37930.620693792705;38271;38318.23363532769;38338.84293399898;38099.23801523899;38438.80780596191;38492;38519.80740096282;38887.80556097201;34467.82766086254;34813.303733925684;35043.12392190216;35092.473612896334;36027.27945441024;32403.18992025124;33158.82711975488;33290.00129996104;33304.50043249402;33718.66281337253;25064.874675626506;25097.498050039263;25238.873805630854;25297.367565811;25352.74400721156 03:48:44 INFO - PROCESS | 11037 | |2;jquery.html;378.36844744799913;385.5835697446758;386.4666759871377;398.3188747242216;409.823775776416;16532.91733541332;17498.737518937225;18436.262549498006;18460.98464584449;18503.167357468912;17467.737983930252;17837.464876053706;17979.640407191848;18194.909025454886;18238.179281932313;5220.921686174701;5422.213779002042;5445.401005889349;5465.016297066526;5473.698946557938;5223.686578805265;5554.416786237448;5563.833085007456;5610.438956104398;5623.662580245178;5444.428335024825;5574.804881829137;5586.91619625706;5598.1322894951345;5598.580106492009;5426.8914621707545;5534.308211473566;5538.0031594313;5565.99999999998;5580.358258800229;5163.096458119814;5380.354357477088;5390.8921821563745;5394.811181608664;5415.864603384907;12042.397880106048;12049.15655904087;20771.857547834865;21067.051982660818;21486.89256553723;1944.2417457191707;1962.7840937496865;1973.289615738333;1996.8602197846158;2015;9058.184763371328;9399.530023498797;9654.855177172349;9795.265355098396;9883.752906177331;1810.5473631592101;2006.3980805758215;2047.4471892588917;2138.1661152150673;2191.4083197536734;59048;70103.29896701069;79333.20666793316;80463.5976820118;80711.596442018;16122.274497647633;16648.667026659397;16682.416115436143;16766.580835478966;17087.487375378758;24102.638460423106;25141.874290628795;30370.54444183339;31288;31360.843195783877;8903.55482225886;9086.182243598108;9206.585703643352;9219.354645174908;9639.421634701941;7739.651715672809;7844.450888437755;7874.56689882062;7972.162922893129;7987.201279871966;8720.825583488295;8727.036061888028;8782.780430489289;8830.55847207637;8995.145461181308;3136.168915237464;3249.431349513826;3323.9347126802263;3347;3351.9608921234008;857.648364170687;880.2605811118691;909.1999040844015;960.8366577681811;965.6330594374093;17052.317907283817;17139.657206855794;17373;17766.644667106844;17791.822081779017;828.6850996621366;834.5910503853069;861.9353548483889;884.5311984648147;907.2787134228304;9544.856827147598;9740.123388895034;9900.504974751233;10004.099631033241;10048.748781280381;368002;374329;375145;376450;378634;70586.64706676434;73259.63370183115;74306.62846685732;76410;76521;915.7893684452613;945.6831961292906;952.661805059194;954.5609019850807;956.2111258212003;15098;15175.696486070437;15349.232538372813;15466.690666186838;15695.215239237765;26629.733702662725;32021.35957280888;32336.35327293488;33357;34431.655683443845;42828.357574637026;46389.76805115953;47411.76294118507;48175.277370840166;48709.269360958904;7546.547207167588;7819.374450043869;7892.464423513701;7904.091029531447;8019.077806052379;8703.793605659059;11185.161112916727;11666.941665291619;11909.344986025852;12041.75916481648;7581.810454738676;10073.992600740161;10378.844317335095;10638.8936110641;10738.83891741609;10191.7452063699;10245.334053286513;10454.947725261325;10511.684649460529;10919.563217471212;10664.466776661291;10815.729606759895;10977.176711746491;10991.230613856953;11054.889451105386;7714.768556943301;7754;7785.532868027936;7862.921370786447;7879.448438609243;10504.634397528273;10507.789844203226;10778.946105269424;10849.349039057683;10984.560617575207 03:48:44 INFO - PROCESS | 11037 | |3;mootools.html;1296.6434230586594;1496.0275820716536;1543.8729727299074;1684.2673437054882;1844.8339649431557;6910.965445172774;7925.603719814015;8183.099859015503;8183.672653093884;8395.83208335833;8100.837983240332;8255.380846436512;8266;8273.089960104384;8275.379346549003;2222.6666000099954;2252.549490101978;2253.6394176931694;2254.627986382245;2256.808171305441;2178.1940681947704;2233.396982814639;2250.797428231459;2254.1772253127574;2256;2100.8214301784365;2110.577884423114;2117.4706323419146;2117.883516406597;2161.8810965396897;2131.4777879419485;2132.1684543028155;2133.9893300533554;2138.016512404296;2140.807327340539;2077.106844057055;2118.1950858673604;2118.226847200776;2119.2582596091397;2120.056574824204;7742.496737712031;7962.681492740284;8149.918500814975;8213.54825484598;8216;4709.105269998689;4799.928001079987;4817.27740838877;4827.275908613701;4835.854924352275;3015.2612609910493;3083.4449799036165;3090.8763649453995;3097.272141046852;3163.810171389701;949.7530642033053;1744.5115367697065;1769.3364988129451;1777.120325438916;1811.7191835265423;10607;10691.518881650345;10692;10694.197935154894;10698.78602427947;4027.6375126238195;4035.616616421435;4143.3784932260405;4147.108371700099;4150.792460376969;8505;8535.146485351415;8541.231289184003;8546.957265213634;8550.273226775793;4632.004119114406;4636.35091087248;4646.930296045562;4651.604613607881;4651.744154071558;3901.356276214393;4246.278132717445;4295.742255464682;4363.694541382073;4427.247367947457;3443.345764304824;3459.4291941829315;3516.5780106387397;3542.1144713821545;3568.892933212008;958.6261358070358;969.1229437359201;978.1832170137873;980.9656662016795;997.9550920208608;770.741801496505;875.3391189651772;899.8290324838391;924.5700749151542;943.7923656795494;5564.749586268627;5611.971940140274;5706.115552089433;5726.541876649858;5885.735141918624;909.7907481279343;910.3445519226146;920.889493260813;931.9813603727888;940.8541676040088;3766.322062028861;3770.3024940386117;3792.886213413602;3832.4251362295877;3840.2127563849344;9760.609575616896;10142.898571014342;10152.289339746147;10206.387616743019;10217.18262538995;9819.558119884623;10099.495025248709;10106.242031847642;10170.135538479311;10179.134773544183;7416.925830741731;7420.888686669704;7506.887396689054;7510.737124200627;7522.699092036257;5161.974190129025;5293.735313234246;5356.946430535801;5488.643238189505;5522.696251706034;8615.569221538773;9104.089591041107;9112.271018318386;9114.453132812052;9122.361434699507;9064.093590640883;9115.042920493253;9168.679096231599;9265.907340926504;9279.304052195977;5768.394318596487;5922.763089476545;6298.149749783904;6395.488360931021;6416.582922110048;7679.654415551202;7858.253465920729;7927.881081783893;7935;7945.404094693036;6753.932460675331;6864;6876.312368763083;7003.509754317149;7039.225685174524;6975.128108986377;6989.860202796017;7032.929670703432;7066.046083778624;7226.638668066471;6145.10895920098;6315.905261421174;6356.650384228912;6450.903236451551;6531.314212007671;4681.110588988081;4739.691920025188;4752.596029337545;4862.426116804076;4877.5366340197625;4785.688930219525;4809.567138957511;4834.492378300369;4863.367762190893;4928.186849169847 03:48:44 INFO - PROCESS | 11037 | |4;prototype.html;307.8106964217007;309.94421004219237;313.04365164422677;314.1001032043197;315.98894038708636;4831.830885918994;4831.951680483198;4878.756062196885;4887.926681099786;4897.1429999750035;4788.3535722677425;4797.568218860302;4836.371271734678;4837.153498137821;4847.957689096844;3631.7457777955556;3724.255148970203;3731.2164445466483;3824.3116239076953;3836.753055257039;3774.773198710416;3802.524684414448;3821.331267028273;3832.42513622956;3842.9039274018232;3634.200475895299;3788.583255841855;3804.90487737806;3810.9428358574505;3821.7706937583835;3592.191756854713;3736.308782875177;3749.6625303722794;3752.8123593820337;3818.083659921627;3359.5128706337687;3369.2924485858;3370.9325813483592;3375.2574433624563;3386.187315044396;4575.679702420832;4658.788714934108;4659.716738797999;4667.203126796174;4682.391289132425;4244.490661120654;4331.242032644274;4338.457692788402;4347.826086956518;4373.759443230621;5077.64456488046;5084.339035925343;5108.489151084862;5108.897822043538;5127.154019586764;3711.888643340704;3721.888343349704;3724.925501489955;3726.9564521933903;3728.067983004249;5061.316722242524;5328.866778330534;5349.7057661828585;5405.972970135124;5408.107662235727;3637.199816040467;3652.1782598915033;3655.3968595182027;3665;3665.706743460517;3675.761075530082;3682.208325210093;3719.9814000929823;3720.795356255378;3721.5348081489815;4276.5723427656985;4293.205756934977;4301.07526881722;4302.311630139163;4303.376010478463;4865.8053677853195;5096.592272618182;5106.974465127725;5113.207452844741;5122.974385128051;1333.1401246196149;1341.550580555525;1346.7373862096797;1348.9797653035212;1351.304078399625;861.3367706865679;886.264400547544;892.808046270042;901.963921443135;906.5739102621757;4296.5273819879785;4355.237833379146;4478.820847166076;4498.3252512123445;4520.638348932078;5204.985027919595;5267.683938963674;5275.129603615437;5300;5304.071787437183;4476.686631935799;4484.618807401343;4488.304312831517;4499.775011249424;4523.570260825217;2624.960625590618;2813.9859300703774;2814.521531339677;2848.8718007689704;2849.216465472012;4941.234108713155;4962.280469331927;4964.163259593974;4977.9590367949695;4986.152354099811;5148.433672296044;5379.946200538023;5388.033367820795;5388.515033646991;5390.056740070472;4942.308076869242;4957.429895561912;4962.950370496321;4965.205567109245;4975.477574854588;5222.477752224899;5228.189630607262;5229.47705229474;5255.973720131527;5257.447967963309;4986.326845875906;5103.260027296095;5126.948730512647;5161.922571161361;5182.378114626269;4899.240617704261;4907.60739140875;4926.359573255597;4943.604511639132;4968.478309777566;3746.5129533160516;3749.7438358149234;3749.7562658427114;3764.7741135531955;3768.773873567595;5045.192769156917;5211.76547055391;5223.895522089613;5267.420583735862;5268.736563171749;5068.26510156018;5071.985602879513;5084.7966081356335;5096.980603879313;5097.92353114711;5079;5112.36095488064;5148.301060650051;5162.328897243335;5167.741612919414;4878.365812444359;5030.974845125751;5065.807547264084;5067.505085999713;5092.159793634009;4947.431045429822;4963.726995015309;5063.721495317793;5063.822766203165;5198.246254293031;4963.70217786934;5003.3245511856885;5012.744265080802;5013.273075404119;5016.473270306566 03:48:44 INFO - PROCESS | 11037 | |5;yui.html;664.6543797225443;700.6181631011099;715.9713611455538;780.7228433905962;795.0221227889696;12614.36928153591;12706.61880143596;12894.22634641921;12919;13407.597772066829;12359.876401235984;12482.563110291141;12557.811632825515;12570.874291257107;12603.558875439361;683.6240067962601;857.1342943626919;875.9605817738217;893.9865902011475;894.8836651235363;879.6481407437044;887.3078998381272;887.5473508510673;887.6538150121457;891.2112780189514;931.2084727981229;931.4364809290383;931.455098767222;933.7152168588577;935.2424536125726;706.6890568149997;723.3092396761081;723.6598798564667;725.437785716069;925.7593025813303;652.6834485274638;672.1832972937904;672.2202245395367;674.0630523572238;675.9594424334507;11784.587539436165;11796.94101529487;11816.05471562273;11816.409179541075;11836;12233.327167005811;12701.872981270162;12980.091393602452;13080.345982700826;13187.802182967263;10592.523336449802;10714.464276786208;10766;10867.510962006729;10982.780344393068;4113.726616425223;4143.7720925349095;4177.561356057631;4195.538490766014;4275.657947364234;45759.313610295874;46046;46064.7696761514;46816.063678726576;46871.76564117157;9852.211823054138;10198.694039178836;10343.534540945675;10407.68776936693;10414.427206503715;18066.09669516519;18108.547286317684;18179.272829086953;18645.627087458448;18687.532811679815;6511.088447617338;6546.214454265423;6615.338466153346;6770.221424536143;6835.624040677711;3769.3403654360377;3783.319002579562;3817.4655548223272;3849.576546579874;3898.161895192547;2859.628248327704;2929.22375570474;2939;2939.955900661492;2943.249471384783;560.7140358417237;585.3473377184408;585.7364186116265;594.8929192745261;598.3956204233755;477.68234124306946;510.43163685019226;576.5238301495089;588.8969430349672;591.6686655472948;8624.52565108925;8706.129387061243;8789.428687135316;8795.736127916172;8860.778480537909;589.5254320272222;609.3571282297219;617.7035023188805;620.3703241210176;633.5121956093782;3741.9251614967548;4022.6379625833815;4033.556308806029;4071.42999980003;4097.528784189797;62539.37460625426;64579.677101614194;65360.67319663466;65395.673021635535;65496.34503654998;56488.43511564914;56664.43335566592;57000.71499642558;57325;58396;50503.24245136326;50561.24158137631;50587.241191382156;50771;51386.74306628443;7611.162772095176;7611.162772095176;7786.4160187985;7840.255175758294;7912.683492660229;29693;31115.222119447197;31636.36727265396;34364;34791.82604087065;40365;40501.594984049785;40620.3906941402;40760.79619601883;41540;10961.83557246626;11016.283941543774;11041.558337666725;11086.113110950942;11298.378589177673;11662.300261984308;11668.124890633067;12066.818997714865;12092.637220883387;12360.81458778137;11562.94218528902;11570.94214528922;11662.416879155839;11789.70525736829;11911.76176476483;11431.463316612886;11635.301881887113;11657.825132623188;11758.470868811095;11834.76330473403;11113.499892504695;11291.887081129084;11595.304281742785;11797;12157.635270941886;9689.27330450205;9950.601975920883;10317.690469285933;10377;10449.84325235107;9892.604295828087;9915;9943.8700440421;9959.253056020683;10080.54637541327 03:48:44 INFO - PROCESS | 11037 | __end_tp_report 03:48:44 INFO - PROCESS | 11037 | __start_cc_report 03:48:44 INFO - PROCESS | 11037 | _x_x_mozilla_cycle_collect,1022 03:48:44 INFO - PROCESS | 11037 | __end_cc_report 03:48:44 INFO - PROCESS | 11037 | __startTimestamp1463482124688__endTimestamp 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | ------- Summary: start ------- 03:48:44 INFO - PROCESS | 11037 | Number of tests: 6 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#0] dojo.html Cycles:165 Average:4669.66 Median:4787.21 stddev:485.91 (10.2%) stddev-sans-first:459.35 03:48:44 INFO - PROCESS | 11037 | Values: 2595.5 2636.4 2670.5 2670.7 2677.2 4605.7 4631.8 4675.0 4684.9 4703.4 4668.1 4717.4 4744.4 4749.9 4755.8 4328.4 4329.6 4417.1 4447.7 4502.6 4423.3 4426.4 4463.3 4465.4 4492.3 4229.4 4280.9 4353.7 4396.7 4409.2 4279.4 4304.0 4306.7 4340.0 4340.6 4309.7 4326.0 4328.4 4328.7 4369.1 4465.8 4501.8 4573.4 4609.4 4625.8 5120.0 5130.8 5134.8 5144.0 5147.6 5111.7 5112.6 5124.5 5142.1 5169.1 4665.7 4728.9 4764.0 4776.8 4799.2 5251.0 5264.5 5303.9 5306.4 5315.2 5050.8 5051.9 5071.3 5082.6 5087.7 4951.0 5017.9 5056.7 5066.3 5081.5 4935.4 4987.7 4993.9 5044.3 5051.0 4885.2 4973.9 4983.4 4988.8 5040.4 4285.7 4313.9 4350.8 4369.7 4423.8 3769.2 3797.1 3798.7 3836.8 3839.6 4382.8 4388.0 4389.6 4395.2 4432.6 4903.8 4953.3 4982.3 4989.4 4999.0 4263.6 4275.0 4283.3 4284.5 4321.4 4509.1 4515.7 4518.3 4526.4 4552.7 4787.2 4808.3 4823.4 4832.5 4837.7 5080.1 5087.1 5093.4 5095.7 5145.7 4620.3 4709.2 4807.9 4822.0 4823.7 4918.0 4954.2 4968.3 4972.1 5012.3 5002.4 5010.2 5015.4 5034.1 5068.6 4758.5 4877.0 4897.1 4911.5 4920.5 4904.1 4920.9 4928.2 4929.9 4967.7 4850.9 4861.4 4891.8 4956.9 4957.6 4789.2 4800.9 4812.5 4813.4 5053.8 4695.9 4716.9 4716.9 4727.3 4777.0 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#1] ext.html Cycles:180 Average:48758.65 Median:200633.82 stddev:85081.67 (42.4%) stddev-sans-first:85243.50 03:48:44 INFO - PROCESS | 11037 | Values: 597.4 606.4 608.8 610.1 614.9 42456.0 43246.4 43656.8 43745.8 43778.0 40735.8 44388.3 44414.3 44454.0 44635.0 7264.9 7958.9 8121.0 8136.3 10118.8 9841.7 10202.3 10204.1 10206.7 10210.3 10226.9 10275.1 10280.8 10283.7 10285.6 10161.5 10168.4 10212.8 10214.0 10218.2 10155.5 10217.6 10225.6 10229.8 10230.8 40398.2 40784.6 40790.6 40820.0 40831.4 3264.3 3512.5 3562.7 3718.9 3833.4 30318.8 30576.8 30582.5 31049.4 31706.7 3442.9 3456.4 3498.9 3511.2 3552.2 189080.0 189627.0 190312.0 190418.0 190504.0 57407.0 57575.1 57643.7 57873.4 57912.7 60695.0 61246.0 61321.0 61388.7 61433.7 15016.1 15303.6 15443.4 15458.1 15469.9 10689.6 12353.3 12631.2 12701.6 12951.7 17105.7 17117.7 17332.9 17346.7 17387.7 6543.9 6658.6 6740.6 6802.7 6859.6 809.4 856.5 861.6 889.0 920.1 28593.6 28760.7 28871.9 29373.6 29575.4 966.5 966.6 994.3 997.1 1070.1 15649.0 15836.0 16021.8 16070.6 16460.3 371119.0 372789.0 375754.0 376295.0 376416.0 53832.7 54039.2 54206.5 54324.5 54334.2 1158.4 1245.0 1247.5 1256.3 1303.8 27308.7 30330.5 30576.2 30779.8 31013.0 47522.5 48171.5 48424.3 48457.3 48493.3 365213.0 369021.0 371207.0 372006.0 372816.0 34978.5 36054.6 41132.6 41816.0 41908.0 44658.8 44673.0 44996.1 45033.5 45967.0 37702.6 37930.6 38271.0 38318.2 38338.8 38099.2 38438.8 38492.0 38519.8 38887.8 34467.8 34813.3 35043.1 35092.5 36027.3 32403.2 33158.8 33290.0 33304.5 33718.7 25064.9 25097.5 25238.9 25297.4 25352.7 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#2] jquery.html Cycles:180 Average:24558.98 Median:44609.06 stddev:61790.91 (138.5%) stddev-sans-first:61937.58 03:48:44 INFO - PROCESS | 11037 | Values: 378.4 385.6 386.5 398.3 409.8 16532.9 17498.7 18436.3 18461.0 18503.2 17467.7 17837.5 17979.6 18194.9 18238.2 5220.9 5422.2 5445.4 5465.0 5473.7 5223.7 5554.4 5563.8 5610.4 5623.7 5444.4 5574.8 5586.9 5598.1 5598.6 5426.9 5534.3 5538.0 5566.0 5580.4 5163.1 5380.4 5390.9 5394.8 5415.9 12042.4 12049.2 20771.9 21067.1 21486.9 1944.2 1962.8 1973.3 1996.9 2015.0 9058.2 9399.5 9654.9 9795.3 9883.8 1810.5 2006.4 2047.4 2138.2 2191.4 59048.0 70103.3 79333.2 80463.6 80711.6 16122.3 16648.7 16682.4 16766.6 17087.5 24102.6 25141.9 30370.5 31288.0 31360.8 8903.6 9086.2 9206.6 9219.4 9639.4 7739.7 7844.5 7874.6 7972.2 7987.2 8720.8 8727.0 8782.8 8830.6 8995.1 3136.2 3249.4 3323.9 3347.0 3352.0 857.6 880.3 909.2 960.8 965.6 17052.3 17139.7 17373.0 17766.6 17791.8 828.7 834.6 861.9 884.5 907.3 9544.9 9740.1 9900.5 10004.1 10048.7 368002.0 374329.0 375145.0 376450.0 378634.0 70586.6 73259.6 74306.6 76410.0 76521.0 915.8 945.7 952.7 954.6 956.2 15098.0 15175.7 15349.2 15466.7 15695.2 26629.7 32021.4 32336.4 33357.0 34431.7 42828.4 46389.8 47411.8 48175.3 48709.3 7546.5 7819.4 7892.5 7904.1 8019.1 8703.8 11185.2 11666.9 11909.3 12041.8 7581.8 10074.0 10378.8 10638.9 10738.8 10191.7 10245.3 10454.9 10511.7 10919.6 10664.5 10815.7 10977.2 10991.2 11054.9 7714.8 7754.0 7785.5 7862.9 7879.4 10504.6 10507.8 10778.9 10849.3 10984.6 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#3] mootools.html Cycles:180 Average:5258.62 Median:4902.86 stddev:2917.58 (59.5%) stddev-sans-first:2910.57 03:48:44 INFO - PROCESS | 11037 | Values: 1296.6 1496.0 1543.9 1684.3 1844.8 6911.0 7925.6 8183.1 8183.7 8395.8 8100.8 8255.4 8266.0 8273.1 8275.4 2222.7 2252.5 2253.6 2254.6 2256.8 2178.2 2233.4 2250.8 2254.2 2256.0 2100.8 2110.6 2117.5 2117.9 2161.9 2131.5 2132.2 2134.0 2138.0 2140.8 2077.1 2118.2 2118.2 2119.3 2120.1 7742.5 7962.7 8149.9 8213.5 8216.0 4709.1 4799.9 4817.3 4827.3 4835.9 3015.3 3083.4 3090.9 3097.3 3163.8 949.8 1744.5 1769.3 1777.1 1811.7 10607.0 10691.5 10692.0 10694.2 10698.8 4027.6 4035.6 4143.4 4147.1 4150.8 8505.0 8535.1 8541.2 8547.0 8550.3 4632.0 4636.4 4646.9 4651.6 4651.7 3901.4 4246.3 4295.7 4363.7 4427.2 3443.3 3459.4 3516.6 3542.1 3568.9 958.6 969.1 978.2 981.0 998.0 770.7 875.3 899.8 924.6 943.8 5564.7 5612.0 5706.1 5726.5 5885.7 909.8 910.3 920.9 932.0 940.9 3766.3 3770.3 3792.9 3832.4 3840.2 9760.6 10142.9 10152.3 10206.4 10217.2 9819.6 10099.5 10106.2 10170.1 10179.1 7416.9 7420.9 7506.9 7510.7 7522.7 5162.0 5293.7 5356.9 5488.6 5522.7 8615.6 9104.1 9112.3 9114.5 9122.4 9064.1 9115.0 9168.7 9265.9 9279.3 5768.4 5922.8 6298.1 6395.5 6416.6 7679.7 7858.3 7927.9 7935.0 7945.4 6753.9 6864.0 6876.3 7003.5 7039.2 6975.1 6989.9 7032.9 7066.0 7226.6 6145.1 6315.9 6356.7 6450.9 6531.3 4681.1 4739.7 4752.6 4862.4 4877.5 4785.7 4809.6 4834.5 4863.4 4928.2 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#4] prototype.html Cycles:180 Average:4230.08 Median:4842.56 stddev:1221.70 (25.2%) stddev-sans-first:1189.13 03:48:44 INFO - PROCESS | 11037 | Values: 307.8 309.9 313.0 314.1 316.0 4831.8 4832.0 4878.8 4887.9 4897.1 4788.4 4797.6 4836.4 4837.2 4848.0 3631.7 3724.3 3731.2 3824.3 3836.8 3774.8 3802.5 3821.3 3832.4 3842.9 3634.2 3788.6 3804.9 3810.9 3821.8 3592.2 3736.3 3749.7 3752.8 3818.1 3359.5 3369.3 3370.9 3375.3 3386.2 4575.7 4658.8 4659.7 4667.2 4682.4 4244.5 4331.2 4338.5 4347.8 4373.8 5077.6 5084.3 5108.5 5108.9 5127.2 3711.9 3721.9 3724.9 3727.0 3728.1 5061.3 5328.9 5349.7 5406.0 5408.1 3637.2 3652.2 3655.4 3665.0 3665.7 3675.8 3682.2 3720.0 3720.8 3721.5 4276.6 4293.2 4301.1 4302.3 4303.4 4865.8 5096.6 5107.0 5113.2 5123.0 1333.1 1341.6 1346.7 1349.0 1351.3 861.3 886.3 892.8 902.0 906.6 4296.5 4355.2 4478.8 4498.3 4520.6 5205.0 5267.7 5275.1 5300.0 5304.1 4476.7 4484.6 4488.3 4499.8 4523.6 2625.0 2814.0 2814.5 2848.9 2849.2 4941.2 4962.3 4964.2 4978.0 4986.2 5148.4 5379.9 5388.0 5388.5 5390.1 4942.3 4957.4 4963.0 4965.2 4975.5 5222.5 5228.2 5229.5 5256.0 5257.4 4986.3 5103.3 5126.9 5161.9 5182.4 4899.2 4907.6 4926.4 4943.6 4968.5 3746.5 3749.7 3749.8 3764.8 3768.8 5045.2 5211.8 5223.9 5267.4 5268.7 5068.3 5072.0 5084.8 5097.0 5097.9 5079.0 5112.4 5148.3 5162.3 5167.7 4878.4 5031.0 5065.8 5067.5 5092.2 4947.4 4963.7 5063.7 5063.8 5198.2 4963.7 5003.3 5012.7 5013.3 5016.5 03:48:44 INFO - PROCESS | 11037 | 03:48:44 INFO - PROCESS | 11037 | [#5] yui.html Cycles:180 Average:14005.84 Median:4186.55 stddev:16819.10 (401.7%) stddev-sans-first:16836.44 03:48:44 INFO - PROCESS | 11037 | Values: 664.7 700.6 716.0 780.7 795.0 12614.4 12706.6 12894.2 12919.0 13407.6 12359.9 12482.6 12557.8 12570.9 12603.6 683.6 857.1 876.0 894.0 894.9 879.6 887.3 887.5 887.7 891.2 931.2 931.4 931.5 933.7 935.2 706.7 723.3 723.7 725.4 925.8 652.7 672.2 672.2 674.1 676.0 11784.6 11796.9 11816.1 11816.4 11836.0 12233.3 12701.9 12980.1 13080.3 13187.8 10592.5 10714.5 10766.0 10867.5 10982.8 4113.7 4143.8 4177.6 4195.5 4275.7 45759.3 46046.0 46064.8 46816.1 46871.8 9852.2 10198.7 10343.5 10407.7 10414.4 18066.1 18108.5 18179.3 18645.6 18687.5 6511.1 6546.2 6615.3 6770.2 6835.6 3769.3 3783.3 3817.5 3849.6 3898.2 2859.6 2929.2 2939.0 2940.0 2943.2 560.7 585.3 585.7 594.9 598.4 477.7 510.4 576.5 588.9 591.7 8624.5 8706.1 8789.4 8795.7 8860.8 589.5 609.4 617.7 620.4 633.5 3741.9 4022.6 4033.6 4071.4 4097.5 62539.4 64579.7 65360.7 65395.7 65496.3 56488.4 56664.4 57000.7 57325.0 58396.0 50503.2 50561.2 50587.2 50771.0 51386.7 7611.2 7611.2 7786.4 7840.3 7912.7 29693.0 31115.2 31636.4 34364.0 34791.8 40365.0 40501.6 40620.4 40760.8 41540.0 10961.8 11016.3 11041.6 11086.1 11298.4 11662.3 11668.1 12066.8 12092.6 12360.8 11562.9 11570.9 11662.4 11789.7 11911.8 11431.5 11635.3 11657.8 11758.5 11834.8 11113.5 11291.9 11595.3 11797.0 12157.6 9689.3 9950.6 10317.7 10377.0 10449.8 9892.6 9915.0 9943.9 9959.3 10080.5 03:48:44 INFO - PROCESS | 11037 | -------- Summary: end -------- 03:48:44 INFO - PROCESS | 11037 | 03:48:45 INFO - TEST-INFO | 11037: exit 0 03:48:45 INFO - TEST-OK | dromaeo_css | took 1119362ms 03:48:45 INFO - TEST-START | kraken 03:48:45 INFO - operating with platform_type : linux_ 03:48:45 INFO - Initialising browser for kraken test... 03:48:45 INFO - TEST-INFO | started process 11215 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzb4HRv/profile http://localhost:41292/getInfo.html) 03:48:50 INFO - PROCESS | 11215 | __metrics Screen width/height:1600/1200 03:48:50 INFO - PROCESS | 11215 | colorDepth:24 03:48:50 INFO - PROCESS | 11215 | Browser inner width/height: 1024/697 03:48:50 INFO - PROCESS | 11215 | __metrics 03:48:51 ERROR - PROCESS | 11215 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:48:51 INFO - TEST-INFO | 11215: exit 0 03:48:51 INFO - Browser initialized. 03:48:51 INFO - Running cycle 1/1 for kraken test... 03:48:51 INFO - TEST-INFO | started process 11297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzb4HRv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:03 INFO - PROCESS | 11297 | RSS: Main: 154251264 03:49:03 INFO - PROCESS | 11297 | 03:49:06 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41292/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:07 INFO - PROCESS | 11297 | RSS: Main: 209526784 03:49:07 INFO - PROCESS | 11297 | 03:49:10 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41292/tests/kraken/driver.html?testName=audio-dft) 03:49:11 INFO - PROCESS | 11297 | RSS: Main: 198336512 03:49:11 INFO - PROCESS | 11297 | 03:49:14 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41292/tests/kraken/driver.html?testName=audio-fft) 03:49:15 INFO - PROCESS | 11297 | RSS: Main: 267063296 03:49:15 INFO - PROCESS | 11297 | 03:49:17 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41292/tests/kraken/driver.html?testName=audio-oscillator) 03:49:17 INFO - PROCESS | 11297 | RSS: Main: 176013312 03:49:17 INFO - PROCESS | 11297 | 03:49:20 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41292/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:20 INFO - PROCESS | 11297 | RSS: Main: 179326976 03:49:20 INFO - PROCESS | 11297 | 03:49:24 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41292/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:24 INFO - PROCESS | 11297 | RSS: Main: 215711744 03:49:24 INFO - PROCESS | 11297 | 03:49:28 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41292/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:29 INFO - PROCESS | 11297 | RSS: Main: 214175744 03:49:29 INFO - PROCESS | 11297 | 03:49:32 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41292/tests/kraken/driver.html?testName=json-parse-financial) 03:49:32 INFO - PROCESS | 11297 | RSS: Main: 214388736 03:49:32 INFO - PROCESS | 11297 | 03:49:34 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41292/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:35 INFO - PROCESS | 11297 | RSS: Main: 204296192 03:49:35 INFO - PROCESS | 11297 | 03:49:37 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:37 INFO - PROCESS | 11297 | RSS: Main: 206348288 03:49:37 INFO - PROCESS | 11297 | 03:49:40 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:40 INFO - PROCESS | 11297 | RSS: Main: 218443776 03:49:40 INFO - PROCESS | 11297 | 03:49:43 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:44 INFO - PROCESS | 11297 | RSS: Main: 224776192 03:49:44 INFO - PROCESS | 11297 | 03:49:47 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:47 INFO - PROCESS | 11297 | RSS: Main: 219115520 03:49:47 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | Cycle 1(1): loaded http://localhost:41292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41292/tests/kraken/driver.html?testName=ai-astar) 03:49:49 INFO - PROCESS | 11297 | RSS: Main: 266268672 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | __start_tp_report 03:49:49 INFO - PROCESS | 11297 | _x_x_mozilla_page_load 03:49:49 INFO - PROCESS | 11297 | _x_x_mozilla_page_load_details 03:49:49 INFO - PROCESS | 11297 | |i|pagename|runs| 03:49:49 INFO - PROCESS | 11297 | |0;ai-astar;114;110;117;115;115;114;115;115;114;115 03:49:49 INFO - PROCESS | 11297 | |1;audio-beat-detection;162;133;152;230;129;158;151;131;190;132 03:49:49 INFO - PROCESS | 11297 | |2;audio-dft;160;161;159;160;162;161;161;161;160;162 03:49:49 INFO - PROCESS | 11297 | |3;audio-fft;77;74;75;151;76;75;77;75;131;77 03:49:49 INFO - PROCESS | 11297 | |4;audio-oscillator;93;94;156;94;94;93;131;94;93;129 03:49:49 INFO - PROCESS | 11297 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;116;117;116 03:49:49 INFO - PROCESS | 11297 | |6;imaging-darkroom;146;146;145;146;146;145;145;145;145;145 03:49:49 INFO - PROCESS | 11297 | |7;imaging-desaturate;95;95;94;96;94;95;95;94;94;95 03:49:49 INFO - PROCESS | 11297 | |8;json-parse-financial;63;62;64;73;63;64;64;115;60;61 03:49:49 INFO - PROCESS | 11297 | |9;json-stringify-tinderbox;64;71;63;64;63;63;64;64;64;71 03:49:49 INFO - PROCESS | 11297 | |10;stanford-crypto-aes;82;82;83;81;81;83;86;82;82;82 03:49:49 INFO - PROCESS | 11297 | |11;stanford-crypto-ccm;134;130;131;137;158;129;129;128;192;134 03:49:49 INFO - PROCESS | 11297 | |12;stanford-crypto-pbkdf2;172;168;171;168;170;169;167;172;170;168 03:49:49 INFO - PROCESS | 11297 | |13;stanford-crypto-sha256-iterative;66;67;69;65;66;70;65;67;65;65 03:49:49 INFO - PROCESS | 11297 | __end_tp_report 03:49:49 INFO - PROCESS | 11297 | __start_cc_report 03:49:49 INFO - PROCESS | 11297 | _x_x_mozilla_cycle_collect,2124 03:49:49 INFO - PROCESS | 11297 | __end_cc_report 03:49:49 INFO - PROCESS | 11297 | __startTimestamp1463482189890__endTimestamp 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | ------- Summary: start ------- 03:49:49 INFO - PROCESS | 11297 | Number of tests: 14 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:1.78 (1.5%) stddev-sans-first:1.88 03:49:49 INFO - PROCESS | 11297 | Values: 114.0 110.0 117.0 115.0 115.0 114.0 115.0 115.0 114.0 115.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#1] audio-beat-detection Cycles:10 Average:156.80 Median:155.00 stddev:31.91 (20.6%) stddev-sans-first:33.79 03:49:49 INFO - PROCESS | 11297 | Values: 162.0 133.0 152.0 230.0 129.0 158.0 151.0 131.0 190.0 132.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 03:49:49 INFO - PROCESS | 11297 | Values: 160.0 161.0 159.0 160.0 162.0 161.0 161.0 161.0 160.0 162.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#3] audio-fft Cycles:10 Average:88.80 Median:75.50 stddev:27.93 (37.0%) stddev-sans-first:29.30 03:49:49 INFO - PROCESS | 11297 | Values: 77.0 74.0 75.0 151.0 76.0 75.0 77.0 75.0 131.0 77.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#4] audio-oscillator Cycles:10 Average:107.10 Median:93.50 stddev:22.91 (24.5%) stddev-sans-first:23.73 03:49:49 INFO - PROCESS | 11297 | Values: 93.0 94.0 156.0 94.0 94.0 93.0 131.0 94.0 93.0 129.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:49:49 INFO - PROCESS | 11297 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:49:49 INFO - PROCESS | 11297 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 03:49:49 INFO - PROCESS | 11297 | Values: 95.0 95.0 94.0 96.0 94.0 95.0 95.0 94.0 94.0 95.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:63.50 stddev:16.58 (26.1%) stddev-sans-first:17.44 03:49:49 INFO - PROCESS | 11297 | Values: 63.0 62.0 64.0 73.0 63.0 64.0 64.0 115.0 60.0 61.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:64.00 stddev:3.14 (4.9%) stddev-sans-first:3.31 03:49:49 INFO - PROCESS | 11297 | Values: 64.0 71.0 63.0 64.0 63.0 63.0 64.0 64.0 64.0 71.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 03:49:49 INFO - PROCESS | 11297 | Values: 82.0 82.0 83.0 81.0 81.0 83.0 86.0 82.0 82.0 82.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:134.00 stddev:20.21 (15.1%) stddev-sans-first:21.31 03:49:49 INFO - PROCESS | 11297 | Values: 134.0 130.0 131.0 137.0 158.0 129.0 129.0 128.0 192.0 134.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:170.00 stddev:1.78 (1.0%) stddev-sans-first:1.64 03:49:49 INFO - PROCESS | 11297 | Values: 172.0 168.0 171.0 168.0 170.0 169.0 167.0 172.0 170.0 168.0 03:49:49 INFO - PROCESS | 11297 | 03:49:49 INFO - PROCESS | 11297 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:66.50 stddev:1.78 (2.7%) stddev-sans-first:1.88 03:49:49 INFO - PROCESS | 11297 | Values: 66.0 67.0 69.0 65.0 66.0 70.0 65.0 67.0 65.0 65.0 03:49:49 INFO - PROCESS | 11297 | -------- Summary: end -------- 03:49:49 INFO - PROCESS | 11297 | 03:49:50 INFO - TEST-INFO | 11297: exit 0 03:49:50 INFO - TEST-OK | kraken | took 65518ms 03:49:50 INFO - SUITE-END | took 1184s 03:49:51 INFO - Completed test suite (00:19:44) 03:49:51 INFO - javascript benchmark 03:49:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2595.5198288316656, 2636.419987602727, 2670.5059563980667, 2670.706222315545, 2677.156695640873, 4605.654575906804, 4631.837885674002, 4674.953250467494, 4684.85945421637, 4703.435587729473, 4668.089722504113, 4717.410323709536, 4744.4069491313585, 4749.881252968669, 4755.833545825897, 4328.394024836518, 4329.610335069843, 4417.094495628388, 4447.688661793676, 4502.57225563571, 4423.336499525065, 4426.358178064179, 4463.285874260119, 4465.419495465601, 4492.303692927601, 4229.429027081351, 4280.9143817123795, 4353.673474489395, 4396.736195828261, 4409.162259170779, 4279.379489973936, 4304.031592891574, 4306.698531102824, 4340.045190058182, 4340.565943405666, 4309.676774241913, 4325.978370108161, 4328.437303150602, 4328.653707703376, 4369.1480161368545, 4465.754383508905, 4501.842435514774, 4573.382593349888, 4609.3777340059, 4625.768711564442, 5120.0, 5130.820421285274, 5134.794608215667, 5144.0, 5147.562457191143, 5111.667741596784, 5112.590992720573, 5124.461931497176, 5142.125838607446, 5169.0954083035695, 4665.6967297125575, 4728.88177795551, 4764.0, 4776.832810851603, 4799.160146974231, 5250.97374513125, 5264.473552644781, 5303.893922121613, 5306.389765176977, 5315.229291752751, 5050.84847454577, 5051.924221136685, 5071.264666623319, 5082.567981721521, 5087.694738315713, 4951.0, 5017.9247311290355, 5056.7471626419265, 5066.316047333563, 5081.491850814963, 4935.358403407606, 4987.725675087832, 4993.85141417476, 5044.344235249394, 5050.989802039533, 4885.193942999365, 4973.875653108629, 4983.426905905795, 4988.775505102279, 5040.36995375578, 4285.678574106954, 4313.935290970638, 4350.847720329773, 4369.6722745794195, 4423.756693381831, 3769.1896242308035, 3797.1266608679607, 3798.734088613826, 3836.7889766062576, 3839.6352346527037, 4382.824687012548, 4387.978060109743, 4389.648828093745, 4395.208862404797, 4432.601065904084, 4903.754812259373, 4953.331300274489, 4982.277569752366, 4989.376327959005, 4999.0, 4263.552327005682, 4275.0, 4283.271843786564, 4284.528701842794, 4321.395004699345, 4509.075639493896, 4515.661325400608, 4518.344839998182, 4526.434195725535, 4552.681312308173, 4787.2101103318255, 4808.2547205183255, 4823.445303790109, 4832.516748325139, 4837.7097374157665, 5080.060188865072, 5087.13518701829, 5093.388793344822, 5095.745212739423, 5145.691258524501, 4620.306953956799, 4709.199436095941, 4807.879803004953, 4821.975890120667, 4823.734694591831, 4918.016396720742, 4954.1825598775795, 4968.254761785761, 4972.0801651694555, 5012.273220383097, 5002.399712034579, 5010.223415370623, 5015.398152221756, 5034.0686972910125, 5068.569171620454, 4758.500357462555, 4877.048975449895, 4897.069556784199, 4911.50884911506, 4920.45874953762, 4904.068227036852, 4920.876978075434, 4928.162212423825, 4929.9014019720125, 4967.652264341461, 4850.854474365775, 4861.392325959255, 4891.75260308619, 4956.8760780980765, 4957.603391728581, 4789.233722604366, 4800.855974320776, 4812.494688057704, 4813.374261346003, 5053.84838454847, 4695.929561056655, 4716.858494245178, 4716.929246061243, 4727.290906363935, 4776.9761151194025], "value": 4638.996033255074, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.4145337569182, 606.426926554406, 608.8143116349513, 610.0574612224113, 614.8800983808158, 42456.0, 43246.35130473045, 43656.78171609141, 43745.78127109368, 43778.0, 40735.796321018424, 44388.3341749874, 44414.33378499325, 44454.0, 44635.0, 7264.891026634578, 7958.880616790739, 8120.9848768903885, 8136.267735903767, 10118.797624047515, 9841.655542056029, 10202.33684810485, 10204.132648724868, 10206.744831379237, 10210.336328138685, 10226.89773102267, 10275.12661423776, 10280.845787313196, 10283.691489255334, 10285.64000259991, 10161.542730577105, 10168.44073575953, 10212.795744085151, 10213.978602139801, 10218.233632477593, 10155.543000564918, 10217.642382516577, 10225.64210252645, 10229.846552301722, 10230.846537301946, 40398.19203615941, 40784.592154078375, 40790.59209407897, 40820.0, 40831.387529187086, 3264.3308121834966, 3512.5082488451635, 3562.6615471530163, 3718.9442158367647, 3833.367494363427, 30318.84840575783, 30576.847115764278, 30582.541261881088, 31049.37901241985, 31706.682933170603, 3442.862285508577, 3456.395130852091, 3498.930021399583, 3511.1924257420683, 3552.236269202108, 189080.0, 189627.0, 190312.0484397583, 190418.0, 190504.0, 57407.0, 57575.13637295444, 57643.71178144083, 57873.421265786805, 57912.710436447545, 60695.0, 61246.0, 61321.0, 61388.69305653443, 61433.692831535554, 15016.099034057774, 15303.61740956485, 15443.382264709511, 15458.072515649097, 15469.922650386676, 10689.62586309491, 12353.258804471761, 12631.178973366701, 12701.555445559547, 12951.676208094685, 17105.657886842193, 17117.743233851503, 17332.91333543324, 17346.739798903025, 17387.65224695499, 6543.869122617616, 6658.602902954933, 6740.561863478858, 6802.693878775467, 6859.588424694534, 809.4253080312926, 856.4946681457969, 861.642418396371, 888.9555522223862, 920.0575533837607, 28593.57109643357, 28760.712392876223, 28871.85564072166, 29373.559396609067, 29575.408491830472, 966.4732720667113, 966.569876404993, 994.2940512236248, 997.0976266478848, 1070.1492313610697, 15649.0, 15836.0, 16021.759673604905, 16070.598235044217, 16460.341586336654, 371119.0, 372789.0, 375754.0, 376295.0, 376416.0, 53832.73083634557, 54039.18941215885, 54206.45793542014, 54324.45675543273, 54334.18498722522, 1158.4092113022418, 1244.9875501245053, 1247.544646204147, 1256.296473974559, 1303.778357679197, 27308.726912731414, 30330.54504182395, 30576.235594110327, 30779.84610076935, 31013.0, 47522.52477475181, 48171.5182848167, 48424.27363589619, 48457.27314090221, 48493.27260091172, 365213.0, 369021.0, 371207.0, 372006.0, 372816.0, 34978.47532287068, 36054.63945360618, 41132.58867411288, 41816.0, 41908.0, 44658.77670611626, 44673.0, 44996.10007799891, 45033.549664502934, 45967.0, 37702.62297376991, 37930.620693792705, 38271.0, 38318.23363532769, 38338.84293399898, 38099.23801523899, 38438.80780596191, 38492.0, 38519.80740096282, 38887.80556097201, 34467.82766086254, 34813.303733925684, 35043.12392190216, 35092.473612896334, 36027.27945441024, 32403.18992025124, 33158.82711975488, 33290.00129996104, 33304.50043249402, 33718.66281337253, 25064.874675626506, 25097.498050039263, 25238.873805630854, 25297.367565811, 25352.74400721156], "value": 18951.865357791725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.36844744799913, 385.5835697446758, 386.4666759871377, 398.3188747242216, 409.823775776416, 16532.91733541332, 17498.737518937225, 18436.262549498006, 18460.98464584449, 18503.167357468912, 17467.737983930252, 17837.464876053706, 17979.640407191848, 18194.909025454886, 18238.179281932313, 5220.921686174701, 5422.213779002042, 5445.401005889349, 5465.016297066526, 5473.698946557938, 5223.686578805265, 5554.416786237448, 5563.833085007456, 5610.438956104398, 5623.662580245178, 5444.428335024825, 5574.804881829137, 5586.91619625706, 5598.1322894951345, 5598.580106492009, 5426.8914621707545, 5534.308211473566, 5538.0031594313, 5565.99999999998, 5580.358258800229, 5163.096458119814, 5380.354357477088, 5390.8921821563745, 5394.811181608664, 5415.864603384907, 12042.397880106048, 12049.15655904087, 20771.857547834865, 21067.051982660818, 21486.89256553723, 1944.2417457191707, 1962.7840937496865, 1973.289615738333, 1996.8602197846158, 2015.0, 9058.184763371328, 9399.530023498797, 9654.855177172349, 9795.265355098396, 9883.752906177331, 1810.5473631592101, 2006.3980805758215, 2047.4471892588917, 2138.1661152150673, 2191.4083197536734, 59048.0, 70103.29896701069, 79333.20666793316, 80463.5976820118, 80711.596442018, 16122.274497647633, 16648.667026659397, 16682.416115436143, 16766.580835478966, 17087.487375378758, 24102.638460423106, 25141.874290628795, 30370.54444183339, 31288.0, 31360.843195783877, 8903.55482225886, 9086.182243598108, 9206.585703643352, 9219.354645174908, 9639.421634701941, 7739.651715672809, 7844.450888437755, 7874.56689882062, 7972.162922893129, 7987.201279871966, 8720.825583488295, 8727.036061888028, 8782.780430489289, 8830.55847207637, 8995.145461181308, 3136.168915237464, 3249.431349513826, 3323.9347126802263, 3347.0, 3351.9608921234008, 857.648364170687, 880.2605811118691, 909.1999040844015, 960.8366577681811, 965.6330594374093, 17052.317907283817, 17139.657206855794, 17373.0, 17766.644667106844, 17791.822081779017, 828.6850996621366, 834.5910503853069, 861.9353548483889, 884.5311984648147, 907.2787134228304, 9544.856827147598, 9740.123388895034, 9900.504974751233, 10004.099631033241, 10048.748781280381, 368002.0, 374329.0, 375145.0, 376450.0, 378634.0, 70586.64706676434, 73259.63370183115, 74306.62846685732, 76410.0, 76521.0, 915.7893684452613, 945.6831961292906, 952.661805059194, 954.5609019850807, 956.2111258212003, 15098.0, 15175.696486070437, 15349.232538372813, 15466.690666186838, 15695.215239237765, 26629.733702662725, 32021.35957280888, 32336.35327293488, 33357.0, 34431.655683443845, 42828.357574637026, 46389.76805115953, 47411.76294118507, 48175.277370840166, 48709.269360958904, 7546.547207167588, 7819.374450043869, 7892.464423513701, 7904.091029531447, 8019.077806052379, 8703.793605659059, 11185.161112916727, 11666.941665291619, 11909.344986025852, 12041.75916481648, 7581.810454738676, 10073.992600740161, 10378.844317335095, 10638.8936110641, 10738.83891741609, 10191.7452063699, 10245.334053286513, 10454.947725261325, 10511.684649460529, 10919.563217471212, 10664.466776661291, 10815.729606759895, 10977.176711746491, 10991.230613856953, 11054.889451105386, 7714.768556943301, 7754.0, 7785.532868027936, 7862.921370786447, 7879.448438609243, 10504.634397528273, 10507.789844203226, 10778.946105269424, 10849.349039057683, 10984.560617575207], "value": 8975.87000462578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.6434230586594, 1496.0275820716536, 1543.8729727299074, 1684.2673437054882, 1844.8339649431557, 6910.965445172774, 7925.603719814015, 8183.099859015503, 8183.672653093884, 8395.83208335833, 8100.837983240332, 8255.380846436512, 8266.0, 8273.089960104384, 8275.379346549003, 2222.6666000099954, 2252.549490101978, 2253.6394176931694, 2254.627986382245, 2256.808171305441, 2178.1940681947704, 2233.396982814639, 2250.797428231459, 2254.1772253127574, 2256.0, 2100.8214301784365, 2110.577884423114, 2117.4706323419146, 2117.883516406597, 2161.8810965396897, 2131.4777879419485, 2132.1684543028155, 2133.9893300533554, 2138.016512404296, 2140.807327340539, 2077.106844057055, 2118.1950858673604, 2118.226847200776, 2119.2582596091397, 2120.056574824204, 7742.496737712031, 7962.681492740284, 8149.918500814975, 8213.54825484598, 8216.0, 4709.105269998689, 4799.928001079987, 4817.27740838877, 4827.275908613701, 4835.854924352275, 3015.2612609910493, 3083.4449799036165, 3090.8763649453995, 3097.272141046852, 3163.810171389701, 949.7530642033053, 1744.5115367697065, 1769.3364988129451, 1777.120325438916, 1811.7191835265423, 10607.0, 10691.518881650345, 10692.0, 10694.197935154894, 10698.78602427947, 4027.6375126238195, 4035.616616421435, 4143.3784932260405, 4147.108371700099, 4150.792460376969, 8505.0, 8535.146485351415, 8541.231289184003, 8546.957265213634, 8550.273226775793, 4632.004119114406, 4636.35091087248, 4646.930296045562, 4651.604613607881, 4651.744154071558, 3901.356276214393, 4246.278132717445, 4295.742255464682, 4363.694541382073, 4427.247367947457, 3443.345764304824, 3459.4291941829315, 3516.5780106387397, 3542.1144713821545, 3568.892933212008, 958.6261358070358, 969.1229437359201, 978.1832170137873, 980.9656662016795, 997.9550920208608, 770.741801496505, 875.3391189651772, 899.8290324838391, 924.5700749151542, 943.7923656795494, 5564.749586268627, 5611.971940140274, 5706.115552089433, 5726.541876649858, 5885.735141918624, 909.7907481279343, 910.3445519226146, 920.889493260813, 931.9813603727888, 940.8541676040088, 3766.322062028861, 3770.3024940386117, 3792.886213413602, 3832.4251362295877, 3840.2127563849344, 9760.609575616896, 10142.898571014342, 10152.289339746147, 10206.387616743019, 10217.18262538995, 9819.558119884623, 10099.495025248709, 10106.242031847642, 10170.135538479311, 10179.134773544183, 7416.925830741731, 7420.888686669704, 7506.887396689054, 7510.737124200627, 7522.699092036257, 5161.974190129025, 5293.735313234246, 5356.946430535801, 5488.643238189505, 5522.696251706034, 8615.569221538773, 9104.089591041107, 9112.271018318386, 9114.453132812052, 9122.361434699507, 9064.093590640883, 9115.042920493253, 9168.679096231599, 9265.907340926504, 9279.304052195977, 5768.394318596487, 5922.763089476545, 6298.149749783904, 6395.488360931021, 6416.582922110048, 7679.654415551202, 7858.253465920729, 7927.881081783893, 7935.0, 7945.404094693036, 6753.932460675331, 6864.0, 6876.312368763083, 7003.509754317149, 7039.225685174524, 6975.128108986377, 6989.860202796017, 7032.929670703432, 7066.046083778624, 7226.638668066471, 6145.10895920098, 6315.905261421174, 6356.650384228912, 6450.903236451551, 6531.314212007671, 4681.110588988081, 4739.691920025188, 4752.596029337545, 4862.426116804076, 4877.5366340197625, 4785.688930219525, 4809.567138957511, 4834.492378300369, 4863.367762190893, 4928.186849169847], "value": 4270.428148117109, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.8106964217007, 309.94421004219237, 313.04365164422677, 314.1001032043197, 315.98894038708636, 4831.830885918994, 4831.951680483198, 4878.756062196885, 4887.926681099786, 4897.1429999750035, 4788.3535722677425, 4797.568218860302, 4836.371271734678, 4837.153498137821, 4847.957689096844, 3631.7457777955556, 3724.255148970203, 3731.2164445466483, 3824.3116239076953, 3836.753055257039, 3774.773198710416, 3802.524684414448, 3821.331267028273, 3832.42513622956, 3842.9039274018232, 3634.200475895299, 3788.583255841855, 3804.90487737806, 3810.9428358574505, 3821.7706937583835, 3592.191756854713, 3736.308782875177, 3749.6625303722794, 3752.8123593820337, 3818.083659921627, 3359.5128706337687, 3369.2924485858, 3370.9325813483592, 3375.2574433624563, 3386.187315044396, 4575.679702420832, 4658.788714934108, 4659.716738797999, 4667.203126796174, 4682.391289132425, 4244.490661120654, 4331.242032644274, 4338.457692788402, 4347.826086956518, 4373.759443230621, 5077.64456488046, 5084.339035925343, 5108.489151084862, 5108.897822043538, 5127.154019586764, 3711.888643340704, 3721.888343349704, 3724.925501489955, 3726.9564521933903, 3728.067983004249, 5061.316722242524, 5328.866778330534, 5349.7057661828585, 5405.972970135124, 5408.107662235727, 3637.199816040467, 3652.1782598915033, 3655.3968595182027, 3665.0, 3665.706743460517, 3675.761075530082, 3682.208325210093, 3719.9814000929823, 3720.795356255378, 3721.5348081489815, 4276.5723427656985, 4293.205756934977, 4301.07526881722, 4302.311630139163, 4303.376010478463, 4865.8053677853195, 5096.592272618182, 5106.974465127725, 5113.207452844741, 5122.974385128051, 1333.1401246196149, 1341.550580555525, 1346.7373862096797, 1348.9797653035212, 1351.304078399625, 861.3367706865679, 886.264400547544, 892.808046270042, 901.963921443135, 906.5739102621757, 4296.5273819879785, 4355.237833379146, 4478.820847166076, 4498.3252512123445, 4520.638348932078, 5204.985027919595, 5267.683938963674, 5275.129603615437, 5300.0, 5304.071787437183, 4476.686631935799, 4484.618807401343, 4488.304312831517, 4499.775011249424, 4523.570260825217, 2624.960625590618, 2813.9859300703774, 2814.521531339677, 2848.8718007689704, 2849.216465472012, 4941.234108713155, 4962.280469331927, 4964.163259593974, 4977.9590367949695, 4986.152354099811, 5148.433672296044, 5379.946200538023, 5388.033367820795, 5388.515033646991, 5390.056740070472, 4942.308076869242, 4957.429895561912, 4962.950370496321, 4965.205567109245, 4975.477574854588, 5222.477752224899, 5228.189630607262, 5229.47705229474, 5255.973720131527, 5257.447967963309, 4986.326845875906, 5103.260027296095, 5126.948730512647, 5161.922571161361, 5182.378114626269, 4899.240617704261, 4907.60739140875, 4926.359573255597, 4943.604511639132, 4968.478309777566, 3746.5129533160516, 3749.7438358149234, 3749.7562658427114, 3764.7741135531955, 3768.773873567595, 5045.192769156917, 5211.76547055391, 5223.895522089613, 5267.420583735862, 5268.736563171749, 5068.26510156018, 5071.985602879513, 5084.7966081356335, 5096.980603879313, 5097.92353114711, 5079.0, 5112.36095488064, 5148.301060650051, 5162.328897243335, 5167.741612919414, 4878.365812444359, 5030.974845125751, 5065.807547264084, 5067.505085999713, 5092.159793634009, 4947.431045429822, 4963.726995015309, 5063.721495317793, 5063.822766203165, 5198.246254293031, 4963.70217786934, 5003.3245511856885, 5012.744265080802, 5013.273075404119, 5016.473270306566], "value": 3848.1260792996663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.6543797225443, 700.6181631011099, 715.9713611455538, 780.7228433905962, 795.0221227889696, 12614.36928153591, 12706.61880143596, 12894.22634641921, 12919.0, 13407.597772066829, 12359.876401235984, 12482.563110291141, 12557.811632825515, 12570.874291257107, 12603.558875439361, 683.6240067962601, 857.1342943626919, 875.9605817738217, 893.9865902011475, 894.8836651235363, 879.6481407437044, 887.3078998381272, 887.5473508510673, 887.6538150121457, 891.2112780189514, 931.2084727981229, 931.4364809290383, 931.455098767222, 933.7152168588577, 935.2424536125726, 706.6890568149997, 723.3092396761081, 723.6598798564667, 725.437785716069, 925.7593025813303, 652.6834485274638, 672.1832972937904, 672.2202245395367, 674.0630523572238, 675.9594424334507, 11784.587539436165, 11796.94101529487, 11816.05471562273, 11816.409179541075, 11836.0, 12233.327167005811, 12701.872981270162, 12980.091393602452, 13080.345982700826, 13187.802182967263, 10592.523336449802, 10714.464276786208, 10766.0, 10867.510962006729, 10982.780344393068, 4113.726616425223, 4143.7720925349095, 4177.561356057631, 4195.538490766014, 4275.657947364234, 45759.313610295874, 46046.0, 46064.7696761514, 46816.063678726576, 46871.76564117157, 9852.211823054138, 10198.694039178836, 10343.534540945675, 10407.68776936693, 10414.427206503715, 18066.09669516519, 18108.547286317684, 18179.272829086953, 18645.627087458448, 18687.532811679815, 6511.088447617338, 6546.214454265423, 6615.338466153346, 6770.221424536143, 6835.624040677711, 3769.3403654360377, 3783.319002579562, 3817.4655548223272, 3849.576546579874, 3898.161895192547, 2859.628248327704, 2929.22375570474, 2939.0, 2939.955900661492, 2943.249471384783, 560.7140358417237, 585.3473377184408, 585.7364186116265, 594.8929192745261, 598.3956204233755, 477.68234124306946, 510.43163685019226, 576.5238301495089, 588.8969430349672, 591.6686655472948, 8624.52565108925, 8706.129387061243, 8789.428687135316, 8795.736127916172, 8860.778480537909, 589.5254320272222, 609.3571282297219, 617.7035023188805, 620.3703241210176, 633.5121956093782, 3741.9251614967548, 4022.6379625833815, 4033.556308806029, 4071.42999980003, 4097.528784189797, 62539.37460625426, 64579.677101614194, 65360.67319663466, 65395.673021635535, 65496.34503654998, 56488.43511564914, 56664.43335566592, 57000.71499642558, 57325.0, 58396.0, 50503.24245136326, 50561.24158137631, 50587.241191382156, 50771.0, 51386.74306628443, 7611.162772095176, 7611.162772095176, 7786.4160187985, 7840.255175758294, 7912.683492660229, 29693.0, 31115.222119447197, 31636.36727265396, 34364.0, 34791.82604087065, 40365.0, 40501.594984049785, 40620.3906941402, 40760.79619601883, 41540.0, 10961.83557246626, 11016.283941543774, 11041.558337666725, 11086.113110950942, 11298.378589177673, 11662.300261984308, 11668.124890633067, 12066.818997714865, 12092.637220883387, 12360.81458778137, 11562.94218528902, 11570.94214528922, 11662.416879155839, 11789.70525736829, 11911.76176476483, 11431.463316612886, 11635.301881887113, 11657.825132623188, 11758.470868811095, 11834.76330473403, 11113.499892504695, 11291.887081129084, 11595.304281742785, 11797.0, 12157.635270941886, 9689.27330450205, 9950.601975920883, 10317.690469285933, 10377.0, 10449.84325235107, 9892.604295828087, 9915.0, 9943.8700440421, 9959.253056020683, 10080.54637541327], "value": 6277.252394780682, "unit": "score"}], "name": "dromaeo_css", "value": 6583.385340075624, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 117.0, 115.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 133.0, 152.0, 230.0, 129.0, 158.0, 151.0, 131.0, 190.0, 132.0], "value": 156.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 160.0, 162.0, 161.0, 161.0, 161.0, 160.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 75.0, 151.0, 76.0, 75.0, 77.0, 75.0, 131.0, 77.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 156.0, 94.0, 94.0, 93.0, 131.0, 94.0, 93.0, 129.0], "value": 107.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 73.0, 63.0, 64.0, 64.0, 115.0, 60.0, 61.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 63.0, 64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 71.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 81.0, 81.0, 83.0, 86.0, 82.0, 82.0, 82.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 130.0, 131.0, 137.0, 158.0, 129.0, 129.0, 128.0, 192.0, 134.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 171.0, 168.0, 170.0, 169.0, 167.0, 172.0, 170.0, 168.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 65.0, 66.0, 70.0, 65.0, 67.0, 65.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1577.1000000000001, "alertThreshold": 2.0}]} 03:49:51 INFO - Return code: 0 03:49:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:49:51 ERROR - # TBPL SUCCESS # 03:49:51 INFO - Running post-action listener: _resource_record_post_action 03:49:51 INFO - ##### 03:49:51 INFO - ##### Finished run-tests step (success) 03:49:51 INFO - ##### 03:49:51 INFO - Running post-run listener: _resource_record_post_run 03:49:51 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 147456; Write bytes: 286318592; Read time: 480; Write time: 179436 03:49:51 INFO - TinderboxPrint: CPU usage
13.1% 03:49:51 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 480 03:49:51 INFO - TinderboxPrint: I/O write bytes / time
286,318,592 / 179,436 03:49:51 INFO - TinderboxPrint: CPU idle
8,334.7 (86.9%) 03:49:51 INFO - TinderboxPrint: CPU user
1,215.3 (12.7%) 03:49:51 INFO - TinderboxPrint: Swap in / out
0 / 0 03:49:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 217088; Read time: 0; Write time: 44 03:49:51 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 147456; Write bytes: 285474816; Read time: 480; Write time: 179364 03:49:51 INFO - Running post-run listener: _upload_blobber_files 03:49:51 INFO - Blob upload gear active. 03:49:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:51 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 03:49:51 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:51 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:52 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:49:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:52 INFO - (blobuploader) - INFO - Done attempting. 03:49:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:57 INFO - (blobuploader) - INFO - Done attempting. 03:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:58 INFO - (blobuploader) - INFO - Done attempting. 03:49:58 INFO - (blobuploader) - INFO - Iteration through files over. 03:49:58 INFO - Return code: 0 03:49:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:49:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:49:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54f47722909f1abda08bdbc72ee64b8cd5bb20204056b70949541f5f7c3abf0be9e4d115a07a6f9563b89755dfcd0b8db51117723c36ff55c4a60b9df6b3c065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5b7b08781bfff00822a60544d1a832c216c3517e194383e86803d24bc5cb1f146ef13a5b2ddf91798e126be2e0e04e85f6c9bf70b4aab5ee7b44a7a734336ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0f3f888cf03d204e969d8ee642465757c484833bb67a5c81fd007d0465895a5a125031f2b7a44a56fe00bceeb82119224cb70009db8fce04dedd885fbd045966"} 03:49:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:49:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:49:58 INFO - Contents: 03:49:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54f47722909f1abda08bdbc72ee64b8cd5bb20204056b70949541f5f7c3abf0be9e4d115a07a6f9563b89755dfcd0b8db51117723c36ff55c4a60b9df6b3c065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5b7b08781bfff00822a60544d1a832c216c3517e194383e86803d24bc5cb1f146ef13a5b2ddf91798e126be2e0e04e85f6c9bf70b4aab5ee7b44a7a734336ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0f3f888cf03d204e969d8ee642465757c484833bb67a5c81fd007d0465895a5a125031f2b7a44a56fe00bceeb82119224cb70009db8fce04dedd885fbd045966"} 03:49:58 INFO - Running post-run listener: copy_logs_to_upload_dir 03:49:58 INFO - Copying logs to upload dir... 03:49:58 INFO - mkdir: /builds/slave/test/build/upload/logs 03:49:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.608678 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-17 03:49:58.294760) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 03:49:58.297960) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/54f47722909f1abda08bdbc72ee64b8cd5bb20204056b70949541f5f7c3abf0be9e4d115a07a6f9563b89755dfcd0b8db51117723c36ff55c4a60b9df6b3c065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5b7b08781bfff00822a60544d1a832c216c3517e194383e86803d24bc5cb1f146ef13a5b2ddf91798e126be2e0e04e85f6c9bf70b4aab5ee7b44a7a734336ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0f3f888cf03d204e969d8ee642465757c484833bb67a5c81fd007d0465895a5a125031f2b7a44a56fe00bceeb82119224cb70009db8fce04dedd885fbd045966"} build_url:https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/JughdRDHRw-lgAF18dL1Rg/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/Fx-Team-Non-PGO/sha512/54f47722909f1abda08bdbc72ee64b8cd5bb20204056b70949541f5f7c3abf0be9e4d115a07a6f9563b89755dfcd0b8db51117723c36ff55c4a60b9df6b3c065", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/5b7b08781bfff00822a60544d1a832c216c3517e194383e86803d24bc5cb1f146ef13a5b2ddf91798e126be2e0e04e85f6c9bf70b4aab5ee7b44a7a734336ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0f3f888cf03d204e969d8ee642465757c484833bb67a5c81fd007d0465895a5a125031f2b7a44a56fe00bceeb82119224cb70009db8fce04dedd885fbd045966"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 03:49:58.325950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:49:58.326737) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1463480517.841690-415928395 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004623 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 03:49:58.377303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 03:49:58.377933) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 03:49:58.378604) ========= ========= Total master_lag: 0.18 =========