builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-045 starttime: 1464878626.25 results: success (0) buildid: 20160602065943 builduid: 0cf9e0a399564eb0a36eb46970ba7b69 revision: 486cf151cbef9c31e108098c9cf809da6e261225 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.250551) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.251014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.251306) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.265617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.266017) ========= 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=4300f881736b422476174874000001bc-1464878626.116488-273983417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-02 07:43:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2016-06-02 07:43:47 (70.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.286183 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.566323) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.566641) ========= 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=4300f881736b422476174874000001bc-1464878626.116488-273983417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018242 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.616695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:46.617065) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 486cf151cbef9c31e108098c9cf809da6e261225 --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 486cf151cbef9c31e108098c9cf809da6e261225 --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=4300f881736b422476174874000001bc-1464878626.116488-273983417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-02 07:43:47,278 truncating revision to first 12 chars 2016-06-02 07:43:47,278 Setting DEBUG logging. 2016-06-02 07:43:47,278 attempt 1/10 2016-06-02 07:43:47,278 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/486cf151cbef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 07:43:47,569 unpacking tar archive at: fx-team-486cf151cbef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630327 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:47.261328) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:47.261667) ========= script_repo_revision: 486cf151cbef9c31e108098c9cf809da6e261225 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:47.262133) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:47.262535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 07:43:47.272359) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-02 07:43:47.272699) ========= /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=4300f881736b422476174874000001bc-1464878626.116488-273983417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:43:48 INFO - MultiFileLogger online at 20160602 07:43:48 in /builds/slave/test 07:43:48 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 07:43:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:43:48 INFO - {'append_to_log': False, 07:43:48 INFO - 'base_work_dir': '/builds/slave/test', 07:43:48 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 07:43:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:43:48 INFO - 'branch': 'Fx-Team-Non-PGO', 07:43:48 INFO - 'buildbot_json_path': 'buildprops.json', 07:43:48 INFO - 'config_files': ('talos/linux_config.py',), 07:43:48 INFO - 'default_actions': ('clobber', 07:43:48 INFO - 'read-buildbot-config', 07:43:48 INFO - 'download-and-extract', 07:43:48 INFO - 'populate-webroot', 07:43:48 INFO - 'create-virtualenv', 07:43:48 INFO - 'install', 07:43:48 INFO - 'run-tests'), 07:43:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:43:48 INFO - 'download_minidump_stackwalk': True, 07:43:48 INFO - 'download_symbols': 'ondemand', 07:43:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:43:48 INFO - 'tooltool.py': '/tools/tooltool.py', 07:43:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:43:48 INFO - '/tools/misc-python/virtualenv.py')}, 07:43:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:43:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:43:48 INFO - 'installer_path': 'installer.exe', 07:43:48 INFO - 'log_level': 'info', 07:43:48 INFO - 'log_name': 'talos', 07:43:48 INFO - 'log_to_console': True, 07:43:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:43:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:43:48 INFO - 'opt_config_files': (), 07:43:48 INFO - 'pip_index': False, 07:43:48 INFO - 'sps_profile': False, 07:43:48 INFO - 'sps_profile_interval': 0, 07:43:48 INFO - 'suite': 'dromaeojs', 07:43:48 INFO - 'system_bits': '32', 07:43:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:43:48 INFO - 'title': 'talos-linux64-ix-045', 07:43:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:43:48 INFO - 'use_talos_json': True, 07:43:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:43:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:43:48 INFO - 'work_dir': 'build'} 07:43:48 INFO - ##### 07:43:48 INFO - ##### Running clobber step. 07:43:48 INFO - ##### 07:43:48 INFO - Running pre-action listener: _resource_record_pre_action 07:43:48 INFO - Running main action method: clobber 07:43:48 INFO - rmtree: /builds/slave/test/build 07:43:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:43:48 INFO - Running post-action listener: _resource_record_post_action 07:43:48 INFO - ##### 07:43:48 INFO - ##### Finished clobber step (success) 07:43:48 INFO - ##### 07:43:48 INFO - ##### 07:43:48 INFO - ##### Running read-buildbot-config step. 07:43:48 INFO - ##### 07:43:48 INFO - Running pre-action listener: _resource_record_pre_action 07:43:48 INFO - Running main action method: read_buildbot_config 07:43:48 INFO - Using buildbot properties: 07:43:48 INFO - { 07:43:48 INFO - "project": "", 07:43:48 INFO - "product": "firefox", 07:43:48 INFO - "script_repo_revision": "production", 07:43:48 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 07:43:48 INFO - "repository": "", 07:43:48 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 07:43:48 INFO - "buildid": "20160602065943", 07:43:48 INFO - "pgo_build": "False", 07:43:48 INFO - "basedir": "/builds/slave/test", 07:43:48 INFO - "buildnumber": 3036, 07:43:48 INFO - "slavename": "talos-linux64-ix-045", 07:43:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:43:48 INFO - "platform": "ubuntu64_hw", 07:43:48 INFO - "scheduler": "tests-fx-team-linux64-talos", 07:43:48 INFO - "branch": "fx-team", 07:43:48 INFO - "repo_path": "integration/fx-team", 07:43:48 INFO - "revision": "486cf151cbef9c31e108098c9cf809da6e261225", 07:43:48 INFO - "stage_platform": "linux64", 07:43:48 INFO - "builduid": "0cf9e0a399564eb0a36eb46970ba7b69", 07:43:48 INFO - "slavebuilddir": "test" 07:43:48 INFO - } 07:43:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 07:43:48 INFO - Running post-action listener: _resource_record_post_action 07:43:48 INFO - ##### 07:43:48 INFO - ##### Finished read-buildbot-config step (success) 07:43:48 INFO - ##### 07:43:48 INFO - ##### 07:43:48 INFO - ##### Running download-and-extract step. 07:43:48 INFO - ##### 07:43:48 INFO - Running pre-action listener: _resource_record_pre_action 07:43:48 INFO - Running main action method: download_and_extract 07:43:48 INFO - mkdir: /builds/slave/test/build/tests 07:43:48 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')]} 07:43:48 INFO - https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:43:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:43:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:43:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:50 INFO - Downloaded 1361 bytes. 07:43:50 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 07:43:50 INFO - Using the following test package requirements: 07:43:50 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:43:50 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:43:50 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:43:50 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'jsshell-linux-x86_64.zip'], 07:43:50 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:43:50 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 07:43:50 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:43:50 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:43:50 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:43:50 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 07:43:50 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:43:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:43:50 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')]} 07:43:50 INFO - https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:43:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:43:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:43:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:51 INFO - Downloaded 22866922 bytes. 07:43:51 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'] 07:43:51 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 07:43:52 INFO - Return code: 0 07:43:52 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 07:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:52 INFO - https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:43:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:43:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 07:43:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:55 INFO - Downloaded 22866922 bytes. 07:43:55 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'] 07:43:55 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 07:43:56 INFO - Return code: 0 07:43:56 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')]} 07:43:56 INFO - https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:43:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:43:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 07:43:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:57 INFO - Downloaded 10834055 bytes. 07:43:57 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'] 07:43:57 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 07:43:58 INFO - Return code: 0 07:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:58 INFO - https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:43:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:43:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:43:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G08sFrTDTomtyTddqBuLHQ/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 07:43:59 INFO - Downloaded 55085235 bytes. 07:43:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:43:59 INFO - mkdir: /builds/slave/test/properties 07:43:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:59 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:59 INFO - Contents: 07:43:59 INFO - build_url:https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 07:44:00 INFO - Running post-action listener: _resource_record_post_action 07:44:00 INFO - Running post-action listener: set_extra_try_arguments 07:44:00 INFO - ##### 07:44:00 INFO - ##### Finished download-and-extract step (success) 07:44:00 INFO - ##### 07:44:00 INFO - ##### 07:44:00 INFO - ##### Running populate-webroot step. 07:44:00 INFO - ##### 07:44:00 INFO - Running pre-action listener: _resource_record_pre_action 07:44:00 INFO - Running main action method: populate_webroot 07:44:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:44:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:44:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:44:00 INFO - '--tppagecycles', 07:44:00 INFO - '7'], 07:44:00 INFO - 'tests': ['tsvgm']}}, 07:44:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:44:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:44:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:44:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:44:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:44:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['damp', 'tps']}, 07:44:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'tests': ['damp', 'tps']}, 07:44:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['dromaeo_dom']}, 07:44:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:44:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'other-e10s': {'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['a11yr', 07:44:00 INFO - 'ts_paint', 07:44:00 INFO - 'tpaint', 07:44:00 INFO - 'sessionrestore', 07:44:00 INFO - 'sessionrestore_no_auto_restore', 07:44:00 INFO - 'tabpaint']}, 07:44:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['tsvgx', 07:44:00 INFO - 'tsvgr_opacity', 07:44:00 INFO - 'tart', 07:44:00 INFO - 'tscrollx', 07:44:00 INFO - 'cart']}, 07:44:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:44:00 INFO - 'tsvgr_opacity', 07:44:00 INFO - 'tart', 07:44:00 INFO - 'tscrollx', 07:44:00 INFO - 'cart']}, 07:44:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'talos_options': ['--disable-e10s'], 07:44:00 INFO - 'tests': ['tp5o']}, 07:44:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'tests': ['tp5o']}, 07:44:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'talos_options': ['--disable-e10s', 07:44:00 INFO - '--xperf_path', 07:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:00 INFO - 'tests': ['tp5n']}, 07:44:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:00 INFO - 'talos_options': ['--xperf_path', 07:44:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:00 INFO - 'tests': ['tp5n']}}, 07:44:00 INFO - 'talos.zip': {'path': '', 07:44:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:44:00 INFO - Running post-action listener: _resource_record_post_action 07:44:00 INFO - ##### 07:44:00 INFO - ##### Finished populate-webroot step (success) 07:44:00 INFO - ##### 07:44:00 INFO - ##### 07:44:00 INFO - ##### Running create-virtualenv step. 07:44:00 INFO - ##### 07:44:00 INFO - Running pre-action listener: _resource_record_pre_action 07:44:00 INFO - Running main action method: create_virtualenv 07:44:00 INFO - Creating virtualenv /builds/slave/test/build/venv 07:44:00 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 07:44:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:44:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:44:00 INFO - Using real prefix '/usr' 07:44:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:44:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:44:02 INFO - Installing pip.................done. 07:44:02 INFO - Return code: 0 07:44:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:44:02 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')]} 07:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:02 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')]} 07:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:02 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:02 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 07:44:02 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 07:44:02 INFO - Using env: {'DISPLAY': ':0', 07:44:02 INFO - 'HOME': '/home/cltbld', 07:44:02 INFO - 'LANG': 'en_US.UTF-8', 07:44:02 INFO - 'LANGUAGE': 'en_US:en', 07:44:02 INFO - 'LOGNAME': 'cltbld', 07:44:02 INFO - 'MAIL': '/var/mail/cltbld', 07:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:02 INFO - 'MOZ_NO_REMOTE': '1', 07:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:02 INFO - 'NO_EM_RESTART': '1', 07:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:02 INFO - 'PWD': '/builds/slave/test', 07:44:02 INFO - 'SHELL': '/bin/bash', 07:44:02 INFO - 'SHLVL': '1', 07:44:02 INFO - 'TERM': 'linux', 07:44:02 INFO - 'TMOUT': '86400', 07:44:02 INFO - 'USER': 'cltbld', 07:44:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:02 INFO - '_': '/tools/buildbot/bin/python'} 07:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:03 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 07:44:03 INFO - Cleaning up... 07:44:03 INFO - Return code: 0 07:44:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:44:03 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')]} 07:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:03 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')]} 07:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:03 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:03 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 07:44:03 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 07:44:03 INFO - Using env: {'DISPLAY': ':0', 07:44:03 INFO - 'HOME': '/home/cltbld', 07:44:03 INFO - 'LANG': 'en_US.UTF-8', 07:44:03 INFO - 'LANGUAGE': 'en_US:en', 07:44:03 INFO - 'LOGNAME': 'cltbld', 07:44:03 INFO - 'MAIL': '/var/mail/cltbld', 07:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:03 INFO - 'MOZ_NO_REMOTE': '1', 07:44:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:03 INFO - 'NO_EM_RESTART': '1', 07:44:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:03 INFO - 'PWD': '/builds/slave/test', 07:44:03 INFO - 'SHELL': '/bin/bash', 07:44:03 INFO - 'SHLVL': '1', 07:44:03 INFO - 'TERM': 'linux', 07:44:03 INFO - 'TMOUT': '86400', 07:44:03 INFO - 'USER': 'cltbld', 07:44:03 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:03 INFO - '_': '/tools/buildbot/bin/python'} 07:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:03 INFO - Downloading/unpacking psutil>=3.1.1 07:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:44:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:05 INFO - Installing collected packages: psutil 07:44:05 INFO - Running setup.py install for psutil 07:44:05 INFO - building 'psutil._psutil_linux' extension 07:44:05 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 07:44:05 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 07:44:05 INFO - building 'psutil._psutil_posix' extension 07:44:05 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 07:44:05 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 07:44:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:44:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:44:05 INFO - Successfully installed psutil 07:44:05 INFO - Cleaning up... 07:44:05 INFO - Return code: 0 07:44:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:44:05 INFO - Using env: {'DISPLAY': ':0', 07:44:05 INFO - 'HOME': '/home/cltbld', 07:44:05 INFO - 'LANG': 'en_US.UTF-8', 07:44:05 INFO - 'LANGUAGE': 'en_US:en', 07:44:05 INFO - 'LOGNAME': 'cltbld', 07:44:05 INFO - 'MAIL': '/var/mail/cltbld', 07:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:05 INFO - 'MOZ_NO_REMOTE': '1', 07:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:05 INFO - 'NO_EM_RESTART': '1', 07:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:05 INFO - 'PWD': '/builds/slave/test', 07:44:05 INFO - 'SHELL': '/bin/bash', 07:44:05 INFO - 'SHLVL': '1', 07:44:05 INFO - 'TERM': 'linux', 07:44:05 INFO - 'TMOUT': '86400', 07:44:05 INFO - 'USER': 'cltbld', 07:44:05 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:05 INFO - '_': '/tools/buildbot/bin/python'} 07:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:44:06 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 07:44:06 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 07:44:06 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 07:44:06 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 07:44:06 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 07:44:06 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 07:44:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:44:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:44:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:44:08 INFO - Installing collected packages: mozsystemmonitor 07:44:08 INFO - Running setup.py install for mozsystemmonitor 07:44:08 INFO - Successfully installed mozsystemmonitor 07:44:08 INFO - Cleaning up... 07:44:08 INFO - Return code: 0 07:44:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:44:08 INFO - Using env: {'DISPLAY': ':0', 07:44:08 INFO - 'HOME': '/home/cltbld', 07:44:08 INFO - 'LANG': 'en_US.UTF-8', 07:44:08 INFO - 'LANGUAGE': 'en_US:en', 07:44:08 INFO - 'LOGNAME': 'cltbld', 07:44:08 INFO - 'MAIL': '/var/mail/cltbld', 07:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:08 INFO - 'MOZ_NO_REMOTE': '1', 07:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:08 INFO - 'NO_EM_RESTART': '1', 07:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:08 INFO - 'PWD': '/builds/slave/test', 07:44:08 INFO - 'SHELL': '/bin/bash', 07:44:08 INFO - 'SHLVL': '1', 07:44:08 INFO - 'TERM': 'linux', 07:44:08 INFO - 'TMOUT': '86400', 07:44:08 INFO - 'USER': 'cltbld', 07:44:08 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:08 INFO - '_': '/tools/buildbot/bin/python'} 07:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:08 INFO - Downloading/unpacking blobuploader==1.2.4 07:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:10 INFO - Downloading blobuploader-1.2.4.tar.gz 07:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:44:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:44:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:44:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:44:11 INFO - Downloading docopt-0.6.1.tar.gz 07:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:44:11 INFO - Installing collected packages: blobuploader, requests, docopt 07:44:11 INFO - Running setup.py install for blobuploader 07:44:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:44:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:44:11 INFO - Running setup.py install for requests 07:44:11 INFO - Running setup.py install for docopt 07:44:11 INFO - Successfully installed blobuploader requests docopt 07:44:11 INFO - Cleaning up... 07:44:11 INFO - Return code: 0 07:44:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44:11 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')]} 07:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 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')]} 07:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:11 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:11 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 07:44:11 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 07:44:11 INFO - Using env: {'DISPLAY': ':0', 07:44:11 INFO - 'HOME': '/home/cltbld', 07:44:11 INFO - 'LANG': 'en_US.UTF-8', 07:44:11 INFO - 'LANGUAGE': 'en_US:en', 07:44:11 INFO - 'LOGNAME': 'cltbld', 07:44:11 INFO - 'MAIL': '/var/mail/cltbld', 07:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:11 INFO - 'MOZ_NO_REMOTE': '1', 07:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:11 INFO - 'NO_EM_RESTART': '1', 07:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:11 INFO - 'PWD': '/builds/slave/test', 07:44:11 INFO - 'SHELL': '/bin/bash', 07:44:11 INFO - 'SHLVL': '1', 07:44:11 INFO - 'TERM': 'linux', 07:44:11 INFO - 'TMOUT': '86400', 07:44:11 INFO - 'USER': 'cltbld', 07:44:11 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:11 INFO - '_': '/tools/buildbot/bin/python'} 07:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:12 INFO - Running setup.py (path:/tmp/pip-GmmOjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:12 INFO - Running setup.py (path:/tmp/pip-x78OCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:12 INFO - Running setup.py (path:/tmp/pip-etLsYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:12 INFO - Running setup.py (path:/tmp/pip-rpF2jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:12 INFO - Running setup.py (path:/tmp/pip-VFMcsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:12 INFO - Running setup.py (path:/tmp/pip-jXsqP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:12 INFO - Running setup.py (path:/tmp/pip-mEmVhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:12 INFO - Running setup.py (path:/tmp/pip-UyJoJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:12 INFO - Running setup.py (path:/tmp/pip-DioOY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:12 INFO - Running setup.py (path:/tmp/pip-2Mppiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:12 INFO - Running setup.py (path:/tmp/pip-3t3CBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:12 INFO - Running setup.py (path:/tmp/pip-qb0yKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:12 INFO - Running setup.py (path:/tmp/pip-dc7a0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:12 INFO - Running setup.py (path:/tmp/pip-OOqFZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:13 INFO - Running setup.py (path:/tmp/pip-x0P9ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:13 INFO - Running setup.py (path:/tmp/pip-mlw9pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:13 INFO - Running setup.py (path:/tmp/pip-aoLa7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:44:13 INFO - Running setup.py install for manifestparser 07:44:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:44:13 INFO - Running setup.py install for mozcrash 07:44:13 INFO - Running setup.py install for mozdebug 07:44:13 INFO - Running setup.py install for mozdevice 07:44:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:44:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:44:13 INFO - Running setup.py install for mozfile 07:44:13 INFO - Running setup.py install for mozhttpd 07:44:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:44:13 INFO - Running setup.py install for mozinfo 07:44:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:44:13 INFO - Running setup.py install for mozInstall 07:44:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Running setup.py install for mozleak 07:44:14 INFO - Running setup.py install for mozlog 07:44:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Running setup.py install for moznetwork 07:44:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Running setup.py install for mozprocess 07:44:14 INFO - Running setup.py install for mozprofile 07:44:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Running setup.py install for mozrunner 07:44:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Running setup.py install for mozscreenshot 07:44:14 INFO - Running setup.py install for moztest 07:44:14 INFO - Running setup.py install for mozversion 07:44:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:44:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:44:14 INFO - Cleaning up... 07:44:15 INFO - Return code: 0 07:44:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:15 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:15 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 07:44:15 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 07:44:15 INFO - Using env: {'DISPLAY': ':0', 07:44:15 INFO - 'HOME': '/home/cltbld', 07:44:15 INFO - 'LANG': 'en_US.UTF-8', 07:44:15 INFO - 'LANGUAGE': 'en_US:en', 07:44:15 INFO - 'LOGNAME': 'cltbld', 07:44:15 INFO - 'MAIL': '/var/mail/cltbld', 07:44:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:15 INFO - 'MOZ_NO_REMOTE': '1', 07:44:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:15 INFO - 'NO_EM_RESTART': '1', 07:44:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:15 INFO - 'PWD': '/builds/slave/test', 07:44:15 INFO - 'SHELL': '/bin/bash', 07:44:15 INFO - 'SHLVL': '1', 07:44:15 INFO - 'TERM': 'linux', 07:44:15 INFO - 'TMOUT': '86400', 07:44:15 INFO - 'USER': 'cltbld', 07:44:15 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:15 INFO - '_': '/tools/buildbot/bin/python'} 07:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:44:15 INFO - Running setup.py (path:/tmp/pip-qUGutV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:44:15 INFO - Running setup.py (path:/tmp/pip-10TutT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:44:15 INFO - Running setup.py (path:/tmp/pip-oiJJea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:44:15 INFO - Running setup.py (path:/tmp/pip-jQ9tMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:44:15 INFO - Running setup.py (path:/tmp/pip-oE1TYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:44:15 INFO - Running setup.py (path:/tmp/pip-UCGj06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:44:15 INFO - Running setup.py (path:/tmp/pip-pXoVeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:44:15 INFO - Running setup.py (path:/tmp/pip-FZOgBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:44:15 INFO - Running setup.py (path:/tmp/pip-RDMyHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:44:15 INFO - Running setup.py (path:/tmp/pip-PUWtiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:44:15 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)) 07:44:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:44:15 INFO - Running setup.py (path:/tmp/pip-cB0IZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:44:16 INFO - Running setup.py (path:/tmp/pip-4bXPky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:44:16 INFO - Running setup.py (path:/tmp/pip-w8Ayzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:44:16 INFO - Running setup.py (path:/tmp/pip-qb0ffq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:16 INFO - Running setup.py (path:/tmp/pip-RccLoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:44:16 INFO - Running setup.py (path:/tmp/pip-ET4jt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:44:16 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)) 07:44:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:44:16 INFO - Running setup.py (path:/tmp/pip-_NyiQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:44:16 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)) 07:44:16 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)) 07:44:16 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)) 07:44:16 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)) 07:44:16 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)) 07:44:16 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)) 07:44:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:44:16 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 07:44:16 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 07:44:16 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 07:44:16 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 07:44:16 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 07:44:16 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 07:44:18 INFO - Downloading blessings-1.6.tar.gz 07:44:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:44:18 INFO - Installing collected packages: blessings 07:44:18 INFO - Running setup.py install for blessings 07:44:18 INFO - Successfully installed blessings 07:44:18 INFO - Cleaning up... 07:44:18 INFO - Return code: 0 07:44:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:44:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:18 INFO - Reading from file tmpfile_stdout 07:44:18 INFO - Current package versions: 07:44:18 INFO - argparse == 1.2.1 07:44:18 INFO - blessings == 1.6 07:44:18 INFO - blobuploader == 1.2.4 07:44:18 INFO - docopt == 0.6.1 07:44:18 INFO - manifestparser == 1.1 07:44:18 INFO - mozInstall == 1.12 07:44:18 INFO - mozcrash == 0.17 07:44:18 INFO - mozdebug == 0.1 07:44:18 INFO - mozdevice == 0.48 07:44:18 INFO - mozfile == 1.2 07:44:18 INFO - mozhttpd == 0.7 07:44:18 INFO - mozinfo == 0.9 07:44:18 INFO - mozleak == 0.1 07:44:18 INFO - mozlog == 3.2 07:44:18 INFO - moznetwork == 0.27 07:44:18 INFO - mozprocess == 0.22 07:44:18 INFO - mozprofile == 0.28 07:44:18 INFO - mozrunner == 6.11 07:44:18 INFO - mozscreenshot == 0.1 07:44:18 INFO - mozsystemmonitor == 0.3 07:44:18 INFO - moztest == 0.7 07:44:18 INFO - mozversion == 1.4 07:44:18 INFO - psutil == 3.1.1 07:44:18 INFO - requests == 1.2.3 07:44:18 INFO - wsgiref == 0.1.2 07:44:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:18 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:18 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 07:44:18 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 07:44:18 INFO - Using env: {'DISPLAY': ':0', 07:44:18 INFO - 'HOME': '/home/cltbld', 07:44:18 INFO - 'LANG': 'en_US.UTF-8', 07:44:18 INFO - 'LANGUAGE': 'en_US:en', 07:44:18 INFO - 'LOGNAME': 'cltbld', 07:44:18 INFO - 'MAIL': '/var/mail/cltbld', 07:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:18 INFO - 'MOZ_NO_REMOTE': '1', 07:44:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:18 INFO - 'NO_EM_RESTART': '1', 07:44:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:18 INFO - 'PWD': '/builds/slave/test', 07:44:18 INFO - 'SHELL': '/bin/bash', 07:44:18 INFO - 'SHLVL': '1', 07:44:18 INFO - 'TERM': 'linux', 07:44:18 INFO - 'TMOUT': '86400', 07:44:18 INFO - 'USER': 'cltbld', 07:44:18 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:18 INFO - '_': '/tools/buildbot/bin/python'} 07:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 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)) 07:44:18 INFO - Cleaning up... 07:44:19 INFO - Return code: 0 07:44:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:44:19 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')]} 07:44:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:19 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')]} 07:44:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:19 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:19 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 07:44:19 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 07:44:19 INFO - Using env: {'DISPLAY': ':0', 07:44:19 INFO - 'HOME': '/home/cltbld', 07:44:19 INFO - 'LANG': 'en_US.UTF-8', 07:44:19 INFO - 'LANGUAGE': 'en_US:en', 07:44:19 INFO - 'LOGNAME': 'cltbld', 07:44:19 INFO - 'MAIL': '/var/mail/cltbld', 07:44:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:19 INFO - 'MOZ_NO_REMOTE': '1', 07:44:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:19 INFO - 'NO_EM_RESTART': '1', 07:44:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:19 INFO - 'PWD': '/builds/slave/test', 07:44:19 INFO - 'SHELL': '/bin/bash', 07:44:19 INFO - 'SHLVL': '1', 07:44:19 INFO - 'TERM': 'linux', 07:44:19 INFO - 'TMOUT': '86400', 07:44:19 INFO - 'USER': 'cltbld', 07:44:19 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:19 INFO - '_': '/tools/buildbot/bin/python'} 07:44:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:19 INFO - Downloading/unpacking jsonschema 07:44:19 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 07:44:19 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 07:44:19 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 07:44:19 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 07:44:19 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 07:44:19 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 07:44:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:44:21 INFO - Downloading/unpacking functools32 (from jsonschema) 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 INFO - Downloading functools32-3.2.3-2.tar.gz 07:44:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:44:21 INFO - warning: no files found matching '*.txt' 07:44:21 INFO - no previously-included directories found matching 'build' 07:44:21 INFO - no previously-included directories found matching 'dist' 07:44:21 INFO - no previously-included directories found matching '.git*' 07:44:21 INFO - Installing collected packages: jsonschema, functools32 07:44:21 INFO - Running setup.py install for functools32 07:44:21 INFO - warning: no files found matching '*.txt' 07:44:21 INFO - no previously-included directories found matching 'build' 07:44:21 INFO - no previously-included directories found matching 'dist' 07:44:21 INFO - no previously-included directories found matching '.git*' 07:44:21 INFO - Successfully installed jsonschema functools32 07:44:21 INFO - Cleaning up... 07:44:21 INFO - Return code: 0 07:44:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:44: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')]} 07:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:44:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44: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')]} 07:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:44:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:44:21 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 0x7f7eb0698f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1902770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, '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': '4300f881736b422476174874000001bc-1464878626.116488-273983417', '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 07:44:21 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 07:44:21 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 07:44:21 INFO - Using env: {'DISPLAY': ':0', 07:44:21 INFO - 'HOME': '/home/cltbld', 07:44:21 INFO - 'LANG': 'en_US.UTF-8', 07:44:21 INFO - 'LANGUAGE': 'en_US:en', 07:44:21 INFO - 'LOGNAME': 'cltbld', 07:44:21 INFO - 'MAIL': '/var/mail/cltbld', 07:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:21 INFO - 'MOZ_NO_REMOTE': '1', 07:44:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:21 INFO - 'NO_EM_RESTART': '1', 07:44:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:21 INFO - 'PWD': '/builds/slave/test', 07:44:21 INFO - 'SHELL': '/bin/bash', 07:44:21 INFO - 'SHLVL': '1', 07:44:21 INFO - 'TERM': 'linux', 07:44:21 INFO - 'TMOUT': '86400', 07:44:21 INFO - 'USER': 'cltbld', 07:44:21 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:21 INFO - '_': '/tools/buildbot/bin/python'} 07:44:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:44:21 INFO - Downloading/unpacking flake8 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:21 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 07:44:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:44:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:44:23 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 07:44:23 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 07:44:23 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 07:44:23 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 07:44:23 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 07:44:23 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 07:44:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:44:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 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 07:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:44:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:44:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:44:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:44:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:44:24 INFO - no previously-included directories found matching 'docs/_build' 07:44:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:44:24 INFO - Running setup.py install for pep8 07:44:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:44:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:44:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:44:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:44:24 INFO - no previously-included directories found matching 'docs/_build' 07:44:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:44:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:44:24 INFO - Cleaning up... 07:44:24 INFO - Return code: 0 07:44:24 INFO - Running post-action listener: _resource_record_post_action 07:44:24 INFO - Running post-action listener: _start_resource_monitoring 07:44:24 INFO - Starting resource monitoring. 07:44:24 INFO - ##### 07:44:24 INFO - ##### Finished create-virtualenv step (success) 07:44:24 INFO - ##### 07:44:24 INFO - ##### 07:44:24 INFO - ##### Running install step. 07:44:24 INFO - ##### 07:44:24 INFO - Running pre-action listener: _resource_record_pre_action 07:44:24 INFO - Running main action method: install 07:44:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:44:25 INFO - Reading from file tmpfile_stdout 07:44:25 INFO - Detecting whether we're running mozinstall >=1.0... 07:44:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:44:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:44:25 INFO - Reading from file tmpfile_stdout 07:44:25 INFO - Output received: 07:44:25 INFO - Usage: mozinstall [options] installer 07:44:25 INFO - Options: 07:44:25 INFO - -h, --help show this help message and exit 07:44:25 INFO - -d DEST, --destination=DEST 07:44:25 INFO - Directory to install application into. [default: 07:44:25 INFO - "/builds/slave/test"] 07:44:25 INFO - --app=APP Application being installed. [default: firefox] 07:44:25 INFO - mkdir: /builds/slave/test/build/application 07:44:25 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'] 07:44:25 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 07:44:41 INFO - Reading from file tmpfile_stdout 07:44:41 INFO - Output received: 07:44:41 INFO - /builds/slave/test/build/application/firefox/firefox 07:44:41 INFO - Running post-action listener: _resource_record_post_action 07:44:41 INFO - ##### 07:44:41 INFO - ##### Finished install step (success) 07:44:41 INFO - ##### 07:44:41 INFO - ##### 07:44:41 INFO - ##### Running run-tests step. 07:44:41 INFO - ##### 07:44:41 INFO - Running pre-action listener: _resource_record_pre_action 07:44:41 INFO - Running main action method: run_tests 07:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:44:41 INFO - Python 2.7.3 07:44:41 INFO - Return code: 0 07:44:41 INFO - grabbing minidump binary from tooltool 07:44:41 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')]} 07:44:41 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 0x19919c0>, '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 0x19928c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1992d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:44:41 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 07:44:41 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 07:44:41 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 07:44:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:44:41 INFO - Return code: 0 07:44:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:44:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:44:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:44:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:44:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:44:42 INFO - Return code: 0 07:44:42 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/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 07:44:42 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/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 07:44:42 INFO - Using env: {'DISPLAY': ':0', 07:44:42 INFO - 'HOME': '/home/cltbld', 07:44:42 INFO - 'LANG': 'en_US.UTF-8', 07:44:42 INFO - 'LANGUAGE': 'en_US:en', 07:44:42 INFO - 'LOGNAME': 'cltbld', 07:44:42 INFO - 'MAIL': '/var/mail/cltbld', 07:44:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:44:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:42 INFO - 'MOZ_NO_REMOTE': '1', 07:44:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:44:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:42 INFO - 'NO_EM_RESTART': '1', 07:44:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:42 INFO - 'PWD': '/builds/slave/test', 07:44:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:44:42 INFO - 'SHELL': '/bin/bash', 07:44:42 INFO - 'SHLVL': '1', 07:44:42 INFO - 'TERM': 'linux', 07:44:42 INFO - 'TMOUT': '86400', 07:44:42 INFO - 'USER': 'cltbld', 07:44:42 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1464878626.116488-273983417', 07:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:42 INFO - '_': '/tools/buildbot/bin/python'} 07:44:42 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/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 07:44:42 INFO - mozversion application_buildid: 20160602065943 07:44:42 INFO - mozversion application_changeset: 486cf151cbef9c31e108098c9cf809da6e261225 07:44:42 INFO - mozversion application_display_name: Nightly 07:44:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:44:42 INFO - mozversion application_name: Firefox 07:44:42 INFO - mozversion application_remotingname: firefox 07:44:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:44:42 INFO - mozversion application_vendor: Mozilla 07:44:42 INFO - mozversion application_version: 49.0a1 07:44:42 INFO - mozversion platform_buildid: 20160602065943 07:44:42 INFO - mozversion platform_changeset: 486cf151cbef9c31e108098c9cf809da6e261225 07:44:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:44:42 INFO - mozversion platform_version: 49.0a1 07:44:42 INFO - using testdate: 1464878682 07:44:42 INFO - actual date: 1464878682 07:44:42 INFO - starting webserver on 'localhost:59082' 07:44:42 INFO - SUITE-START | Running 2 tests 07:44:42 INFO - TEST-START | dromaeo_css 07:44:42 INFO - operating with platform_type : linux_ 07:44:42 INFO - Initialising browser for dromaeo_css test... 07:44:42 INFO - TEST-INFO | started process 2597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMElqVQ/profile http://localhost:59082/getInfo.html) 07:44:46 INFO - PROCESS | 2597 | __metrics Screen width/height:1600/1200 07:44:46 INFO - PROCESS | 2597 | colorDepth:24 07:44:46 INFO - PROCESS | 2597 | Browser inner width/height: 1024/697 07:44:46 INFO - PROCESS | 2597 | __metrics 07:44:47 ERROR - PROCESS | 2597 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:44:48 INFO - TEST-INFO | 2597: exit 0 07:44:48 INFO - Browser initialized. 07:44:48 INFO - Running cycle 1/1 for dromaeo_css test... 07:44:48 INFO - TEST-INFO | started process 2730 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMElqVQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:45:00 INFO - PROCESS | 2730 | RSS: Main: 158199808 07:45:00 INFO - PROCESS | 2730 | 07:45:52 INFO - PROCESS | 2730 | 1464878752142 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] 07:48:03 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59082/tests/dromaeo/cssquery-ext.html) 07:48:04 INFO - PROCESS | 2730 | RSS: Main: 268517376 07:48:04 INFO - PROCESS | 2730 | 07:51:07 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-ext.html (next: http://localhost:59082/tests/dromaeo/cssquery-jquery.html) 07:51:08 INFO - PROCESS | 2730 | RSS: Main: 232673280 07:51:08 INFO - PROCESS | 2730 | 07:54:10 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59082/tests/dromaeo/cssquery-mootools.html) 07:54:11 INFO - PROCESS | 2730 | RSS: Main: 239562752 07:54:11 INFO - PROCESS | 2730 | 07:57:13 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59082/tests/dromaeo/cssquery-prototype.html) 07:57:13 INFO - PROCESS | 2730 | RSS: Main: 239280128 07:57:13 INFO - PROCESS | 2730 | 08:00:16 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59082/tests/dromaeo/cssquery-yui.html) 08:00:17 INFO - PROCESS | 2730 | RSS: Main: 212213760 08:00:17 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | Cycle 1(1): loaded http://localhost:59082/tests/dromaeo/cssquery-yui.html (next: http://localhost:59082/tests/dromaeo/cssquery-dojo.html) 08:03:20 INFO - PROCESS | 2730 | RSS: Main: 266919936 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | __start_tp_report 08:03:20 INFO - PROCESS | 2730 | _x_x_mozilla_page_load 08:03:20 INFO - PROCESS | 2730 | _x_x_mozilla_page_load_details 08:03:20 INFO - PROCESS | 2730 | |i|pagename|runs| 08:03:20 INFO - PROCESS | 2730 | |0;dojo.html;2653.81423300369;2657.123149360711;2690.609861570073;2707.526182917989;2719.0891051497747;4626.097910907374;4662.044280922411;4690.695104818193;4724.267738500534;4783.856484305472;4668.463126740426;4669.953300466995;4670.743109128996;4696;4698.506656801038;4095.365218391154;4235.830566777326;4324.6107850293465;4351.630111440516;4447.110577884436;4390.165868484994;4426.889327766816;4427.269500532424;4447.288433850585;4471.865844024668;4227.76747278898;4276.144771045803;4313.374560688714;4343.305071188594;4357.237483440417;4297.570242975708;4303.419038429804;4311.50417701962;4353.651707863363;4357.193919124972;4322.37325587788;4332.155229730204;4354.716943398669;4386.6929314947965;4396.362527433536;4534.9319760203625;4619.792109355087;4662.160811054009;4666.043461090502;4685.5782979532005;5106.846794596131;5131.53816156544;5136.178211486182;5137.126688462933;5137.229415587654;5090.287359769635;5139.717315547643;5162.5095615916425;5165.173572228426;5174.741262936838;4615.284630882219;4716.9056618867435;4757.381540399795;4763.071201115749;4782.617390608743;5209.062368773657;5234.712090835041;5238.214267859851;5291.206319052173;5293;4806.663533552688;5023.8492845214705;5071.03650306448;5075.187969924795;5075.670081444694;5039.370078740158;5072.112380333427;5083.694978301313;5090.643654944119;5109.642325037286;5091.210862316347;5091.770870310845;5093.286939828353;5101.566366858784;5144.43411224765;4908.975455122773;4915.11527924977;5061.063703214917;5068.771905264272;5077.746112694351;4282.8715138545895;4303.892402689895;4306.956930430656;4338.240807858612;4349.891252718708;3710.5361829771277;3727.9254414912093;3733.8879833605033;3767.3407153747985;3781.5840257571645;4266.338717498793;4303.182395344875;4307.310830267143;4350.0647360817575;4354.738715677006;5017.774200161001;5029.321041659402;5137.768800403991;5168.638195326366;5183.611229157828;4438.356438316426;4452.532484089189;4471.217536931024;4472.485664148665;4489.304157855538;4650.37219975299;4662.95337046632;4679.649026322972;4680.110778951988;4709.152352576569;4685.765711714401;4728.007118505084;4737.076270127292;4776.426828780569;4792.856214313576;5060.898782024413;5092.668976516515;5097.311862898461;5101.617378696538;5151.639385242997;4792.568668819823;4800.447948485829;4805.6155507558615;4806.735629540409;4839.056384005085;4902.264660300983;4944.802207911644;4976.303317535476;4994.225894986283;5000.699958002531;5031.144705400164;5057.367829021372;5068.239764035424;5082.949170508247;5116.258142569232;4733.242681170997;4923.187674033888;4936.851894443173;4942.975285123552;4989.326440930573;4884.096442158212;4930.580900623344;4934.5312195341385;4940.505949405175;4965.8013679452415;4883.8290659826735;4885.389326334208;4938.975305123451;4939.061578300111;4941.901161976813;4871.439784424837;4944;4996.650234483696;5034.496550345083;5054.671446356122;4824.276358546246;4855.562999330077;4887.633427492881;4893.657443978887;4936.111499930047 08:03:20 INFO - PROCESS | 2730 | |1;ext.html;525.3881256488883;591.3140756722202;594.5956749410399;595.9523238140949;608.3581821178658;27895.581566276498;30725.38549229014;30795;41487;42714.14571708564;40288.597114028926;40579;40981.59018409815;40991.79504102483;41012;7585.089789225301;7766.145723970359;7769.611519424035;7784.727534536292;7854.057513098436;7469.738559150431;9927.106560409562;10276.794464110713;10278.331908425966;10629.096526795232;10017.649382271622;10093.192544596415;10103.191744660446;10106.797864042715;10112;7469.813254668622;7707.653155607955;10193.490325483734;10275.537600807982;10295.124914382286;7560.395168386516;10041.347312424668;10480.47597620116;10504.474776261157;10549.736256593647;39442;39839.203215935806;39880.40179397311;39938.80030599829;39997.200055998714;3112.2841746398453;3156.447621666199;3213.437648411542;3504.842282097312;3748.137928276484;29752.256193595334;30076.548851767242;30195.849020754755;30500.237494062825;30546.69453305483;3577.767445116059;3583.8566457341676;3585.64143585642;3613.4218525035867;3661.6704496595435;187021;187574;187574;187641;188117;56424.43575564274;57377.71311143501;57486.71256643774;57520.71239643859;57558.7122064387;60136.69931650314;60142;60214.69892650596;60227.096593551134;60287;14951.476698315506;15463.690726185414;15488.612784680248;15498.612534686723;15500.069995800062;10180.23648226386;10626.787464250672;10666.520006599721;11502.194846360675;12017.15879888417;17619.030953297693;17622.559436014202;17669.20488578017;17675.911620441817;17695.203715832817;6100.3899610039525;6114.7502485771965;6177.536684748662;6197.287311959182;6201.937980620226;849.9490030598184;953.4851180362665;1013.9188864890791;1040.3237895368072;1055.18223376882;28039.859800700866;28374;28764;28780.856095719806;28806.567901481496;964.7298756348234;995.8307087795092;1009.7627057641448;1057.6139709006156;1074.119222237773;14820.555383338517;15297.158656274012;15302.387904483918;15490.535283941499;15641.60895977587;357197;358215;361174;361346;361802;50702.49297507051;50799.746001269756;50813;50855.74572127116;50907;1097.8902109789117;1142.4059489065805;1224.1797995342963;1227.0429065329058;1249.925004499733;26327.341816453976;29557.55663665004;29592.40815183641;29611.259718507208;30169.849150754108;43754;44393.55606443894;44459.33311000273;44518;44521.55478445304;275305;275465;276062;276765;277196;31712.841435792674;37358.3188756512;37696.811515942245;38234.23531529409;38602.80698596489;43487.78256108699;43590.78204608957;43949.56050439455;43976;44985;37006.629933700315;37009.444858326606;37492;37680;37734.81132594429;36549.26901462009;36950;37247.62752372442;37497;37681;36492.27015459729;36615.816920915226;36648.63351366452;37120.28797120375;37291.44062839114;32061.51907721433;32518.748125187292;32939;32949.88247480153;33308.500372493945;25116.49767004613;25428.491430171664;25471.745282546937;25477.490450191264;25771.613425798252 08:03:20 INFO - PROCESS | 2730 | |2;jquery.html;373.2665312660622;374.58421152520697;374.7751349190486;382.4015415874155;387.24100762505486;15681.686366272668;16789.916050419746;17642.823571764293;17682.82317176828;17687.46937591871;11726.824097638542;15681.451149209779;16451.095189764586;16483.423080192195;17068.317267309325;4414.205443020255;4520.796564154621;4539.977300113512;4544.272916333387;4545.499995000548;3516.226430185355;3637.8362973666117;4674.439067311927;5157.48425157485;5432.022235997519;5469.699166545838;5470.261514695525;5492.258545096401;5496.725163741817;5500.1749737539485;5068.265101560271;5360.437154098802;5372.7313634318325;5424.430434804328;5474.260974768415;3364.8654053837818;4982.252662100677;5265.341832270966;5310.0441920454305;5325.4142044375085;19507.80492195074;19824.107915143853;20193.697094543593;20333;20352.592948141104;1780.4302623160593;1820.8088150744245;1887.3111314370294;1938.6510428122933;1963.1558429875147;8450.830983380298;8519.74440766778;8833;9058.954705226432;9194.540272986324;1704.3012364930319;1721.7073097573443;1723.2848367927295;1729.3601367494107;1756.2535922233;65405;69072.6546367265;70834.64582677105;71312;72506.27493725048;9932.404055756677;9954.104130628133;10107.545160467796;10125.64560240388;10262.538185781657;13823.447062117402;13960.650983725489;13971.161730296215;13974;13983.86016139846;7478.102627684712;7583.5070720403;7679.1936846631415;7765.417593680497;7830.373570114377;7343.669534870944;7344.669489872968;7350.375218106414;7503.174650788409;7517.361024312994;4335.956640433556;4342.544032876565;4356.542563030899;4393.319035549495;4400.691951563424;1669.6577201673629;1692.3146125819062;1768.5136587438558;1810.2216047099873;1822.1162736072995;844.5355054720002;917.6329468212634;921.5254144115786;958.1759686669459;977.296346630425;16002;16410.1794910254;16667;16867.578310542336;17025;872.3152325424511;885.2519620920441;912.4069354919222;940.4780346907532;946.3139224062637;9407.4825884577;9463.290253231035;9613.663521776843;9650.104968509419;9842.950785246172;344076;344862;347763;350713;353173;71725;74285.6285718568;74350.62824685842;74470;74537.62731186418;852.2968550945495;889.822035592884;903.8915330160423;929.2612373163352;932.021377553566;12772.808407873703;15329.38682452734;15526.06843589343;15696.136712480924;15828.52514424569;34480.137996550286;35293.47059794157;35369;36027.459588105674;36423;45293;45342.77328613336;45929.540704592524;46195.76902115468;46928.29607555821;7194.280571942973;7225.096862892138;7225.205227425085;7299.671514781954;7508.099028116442;8026.8394632105865;9930.801383972135;10436.112930400697;10898.237123401286;11214.943925280322;9509.857352139861;9522.809543809224;9632.614695412105;9793.706188814347;9858.260630452889;7706;9126.771830704285;9449.811003780023;9651.372660777028;10648.84026739615;5246.449122842047;7865.842683146419;9851.571522129394;10330.638427654996;10462.843057354297;7519.210482899435;7617.466777325534;7661.080670319598;7723.382129429745;7935.769955656882;8821.911780882108;10389;10474;10783.676489705322;10920.344779313267 08:03:20 INFO - PROCESS | 2730 | |3;mootools.html;1206.2340413837221;1413.6324555615538;1423.3950571007322;1534.6777176792873;1765.5674359781856;6229.84425389365;7371.963140184298;7634.045744281965;7646.350060244872;7852.60736963151;7603.391728661708;7757.5733334666575;7761.379089672827;7768.262015108556;7769.261920117594;2220.4448887778094;2252.256755270765;2253.785890340423;2256.3907744908865;2259.491614386758;2176.3035828534876;2205.305328821424;2216.2575537195057;2216.4015715756736;2221.9333419997347;2188.26693057825;2190.715207023085;2204.7574766775642;2209.1826024370926;2225.2434172381386;2156.5147841735643;2177.42298290953;2181.858179218362;2185.2024011235862;2186.529896072344;2113.1019316790303;2115.143366936378;2118.5233322502468;2118.851680382374;2120;7491.850162996764;7579.1662917079075;7637.656305466212;7648.2351764823625;7651;4447.577480139414;4604.769761511911;4608.976955115203;4616.053708989649;4622.144903192887;2774;2925.283305590144;2977.3152174999873;2982.284251779579;2993.161914663876;918.3984490158956;961.5721004153155;971.4996776660025;974.2108891797595;999.6601155607055;10159.13647339977;10185.694429167137;10186.490675466197;10270.897291027144;10317.742056448575;4088.509378874554;4127.752334859918;4172.70791044624;4356.455443069616;4364.650827933821;8028.638711258008;8065.798355041054;8106.148854370324;8117.350611951029;8117.391195660231;4497.595216430537;4537.659675524349;4540.659450541222;4550.840720574764;4558.3162525620755;3701.8889433317045;3839.500864887547;4264.253755592759;4270.316749320094;4286.485621725351;3467.133216695826;3508.1755787389943;3536.6463353664944;3560.4303311470558;3589.066842620885;1039.8336266197373;1040.9635662751768;1044.9059584637419;1045.9215558833118;1050.4327663062013;687.7386593094592;737.4248086492545;832.1262674192075;841.179849646602;854.4616891358529;5187.340051183585;5241.868953276123;5255.868603284872;5279.366476022825;5450.427705090988;400.2914840731946;407.27708317736256;408.2997659014804;409.5413137286258;410.05073255413623;3607.85568577254;3634.309481198564;3691.335559599298;3743.756655817363;3825.8087095645105;9097.454152750724;9301;9377.578008989612;9396.389234699722;9412.811743765224;9290.767730806649;9390.295727820305;9391.436513809193;9397.530123493798;9407.388519746195;6922.7923162304205;7162.355388015104;7170.820729481805;7252.927470725331;7254.419646428273;5122.129238029469;5211.921821172761;5227.529522343008;5240.318758561362;5306.6550674206055;8497.362697797813;8584.184502472255;8838.160374764388;8845.203931646183;8857.778555536162;8790.780230494289;8814.603342849716;8925.955370223368;8927.866082008644;8940.016598174325;5417.349918009864;5910.23166988306;6073.240844894388;6095.573309868267;6221.440070393686;7357.632118394166;7371.705131794668;7375.8156046099275;7379.963100184465;7411.66647500853;6621.5364924454825;6668.533202675961;6739.292374300628;6837.487188460985;6899.689513971783;6668.599884006975;6683.097781799589;6694.0628312037325;6743.29195434492;6807.285235050248;6246.656433896179;6259.405356491126;6260.7495700171485;6296.968515157578;6335.113084168128;4545.227311357011;4592.311153326894;4614.353990441408;4667.346571479928;4700.318453824245;4609.308603709471;4673.509281525527;4691.272852707836;4730.290456431425;4767.713937163781 08:03:20 INFO - PROCESS | 2730 | |4;prototype.html;299.6134985868229;302.71393533111205;310.1022539747432;314.5973154362417;318.1156385249007;4832.173178690466;4878.170710979129;4882.413215704902;4913.606911447085;4919.4096708395;4806.519348065201;4808.615310775138;4826.951730482694;4843.418789745236;4844.951550484494;3555.822208889558;3678.889633311005;3691.6308369163003;3693.580704638466;3774.7923864187596;3678.448232765093;3701.370766969622;3809.29528037314;3823.1780167264033;3861.5945325740813;3728.9627103728885;3767.585565587783;3772.6038765929593;3775.470434808685;3788.299164654534;3531.2054787672823;3746.5129533160516;3771.8679846205528;3793.5637401698746;3799.4680744695765;3287.4904389819617;3288.6516528223315;3309.0900002499266;3309.7352211822995;3313.353895990283;4642.117997580449;4645.117427688762;4666.346711460398;4670.556297151765;4687.1328804171335;4162.146759914211;4308.18144552534;4310.547392523771;4319.632831209351;4334.263175260214;5058.266551350033;5058.671186372911;5064.949350506485;5069.8732531687;5071.46749591295;3590.982045089784;3593.9101522462147;3612.801295928723;3622.2574372253625;3630.7821530708243;5062.620303477254;5323.041852466555;5338.733063346817;5339.917432201444;5365.490278423505;3703.666669999659;3704.036950392863;3705.7220708446976;3714.312852122312;3723.1436769543157;3764.698824094066;3772.5472943246436;3779.829907654162;3779.981100094482;3788.450674652215;4308.892277693083;4328.588784065509;4336.154449882306;4343.5656434356315;4344.913101737948;4925.335079764258;5111.722069482629;5132.923006154911;5136.404516838228;5172.482751724798;1269.9174553653984;1289.8065290206357;1292;1296.0797833538293;1300.206873806977;800.1518390506083;823.6458322921081;836.0135040651968;844.8648216285364;851.4082712514743;4432.556744325541;4511.593956543927;4518.1415531049;4520.434945631796;4542.182407166676;5229.555487783505;5236.162214045735;5243.8274843719;5252.816151434681;5288.81489147878;4451.910961780811;4492.303692927601;4495.910081798345;4520.7061541000385;4522.140793249272;2650.9204723858315;2815.4087641595493;2818.788590855653;2820.083472871325;2821.3369858083333;4960.330355402047;4964.553190212898;4964.702117872867;4993.350864387607;5006.374203224597;5285.445028271976;5362.785488580491;5378.139497680352;5416.050221920105;5419.403865574784;4988.575971042429;5014.373203349654;5014.824481143142;5016.774245158904;5022.321986531844;5319.281896943863;5321.760520776497;5331.1470164773455;5332.573394128538;5333.599980001438;5021.447640759587;5160.200168973815;5234.947650523446;5247;5250.553702935294;4971.453140154653;4991.575716064175;5005.549500544968;5008.69947803133;5032.672876263031;3385.813780242012;3417.487376893386;3429.605595356466;3436.1924947637785;3441.967409777107;4495.483019452674;4701.672672027368;4713.010267843654;4771.403574553181;4779.259214821708;4550.544945505423;4604.815807367668;4632.9305060424795;4637.39713837199;4668.719876807402;4524;4562.315652652129;4593.471750748707;4623.329617205553;4653.834817838084;4332.6317263033;4471.374007638999;4474.574915383034;4526.456825181;4527.939787396793;4608.539146085364;4908.484609115992;5036.010775743487;5181.896362072813;5193.909279051441;4965.4041515018425;4972.627052971114;4973.229149481836;4979.427365852974;5001.549860512571 08:03:20 INFO - PROCESS | 2730 | |5;yui.html;638.9297177310495;643.7907680004;657.7237560224709;741.0958630470824;760.3992845651934;12706.301153436558;13697.452101915937;13721.588352349432;13724.107932984374;13739.313034348268;12205.57280495183;12368.628941131781;12382.071344649165;12767.808482872764;12859.099863009593;633.5628416392674;790.036155889815;800.1038836503095;800.5476905548376;802.4423025996934;778.136268741699;797.2067792546425;804.6620419423856;806.4757907360233;807.7818988873;849.8470275350435;851.2934264560431;851.3019324154166;852.8763329317245;853.2022558907411;660.884345239586;822.2599660305713;848.9405741598091;849.0024221539697;849.4860609331328;614.2322097378277;623.688155922039;623.8097380299007;623.8752249550063;624.1199908129515;12415.193012454161;12453.377331133406;12461.376931153405;12472.002239820793;12490.12569120162;12709.300988445631;13100.541481048213;13228.338583070808;13316.667083322898;13589.252591107484;11652;11882.28706277626;11936.28382297065;12007.759844803055;12355.62933112008;4221.493420789494;4234.216669916075;4272.743635381856;4311.267084595626;4397.9560204398185;61751.69124154395;62474.37525624731;63456;63542;63939;9617.326787124834;9784.804303913881;9857.704268871801;10625.734356641145;10699.465026748632;18217.817821821878;18397.172127254307;18575.071246437623;18628.254869805056;18638;6718.596884186867;6761;6840.726370945206;6887.483438742114;7001.29987001306;3643.1620727232885;3871.1870507193244;3926.1362500249897;3940.054386947112;4058.066644671742;2966.46603611352;3134.341788224453;3135.9686403135674;3136.4197623439736;3139.952900706491;553.2641586689693;557.8214971209175;564.3397225246472;566.7817890112254;567.6310398241094;501.5385845022588;580.3413126101821;600.2017316968421;620.8292719502173;631.6115588912786;8537.274331681876;8994.190522852974;9024;9058.411203271766;9090;554.6671996801887;562.7664519224476;595.3778301674761;598.850287428143;623.9344868788803;3894.90262743428;3968.8412463501713;4059.959400405958;4095.447114639545;4099.877003689834;81295;83715.5814220925;91311.54344228236;91400.54299728591;91651;47625.28562071572;65980.67009665017;66158.66920665462;67612;72950.63524682343;66955.66522167358;67025.32974670191;69341.65329173322;69375;71644.2835571648;8912.465252084896;9003.279737620844;9026.142516460926;9051.185393314634;9059.637614495346;31695.52456713105;31810.522842156923;38904.80547597244;41963.580364195965;42733.871935367955;54989.72505137609;55187;56781.71609141928;61163.69418152881;61796.69101654463;10826.458677065957;11017.008469237808;11500.654980350602;11674.182807203415;11787;12559.309237991998;12790.552330668754;12872.742545149233;13346;13476.595702128952;7762.68949242024;11671.59149429766;11676.416179191176;11952.76094478088;12081.275123492618;11920.642380728592;12068.649545555789;12259.570915018288;12476.376181191086;12645.620631381073;11704;11963.581274655346;12008.219465734699;12313.876861231272;12393.566225182076;9691.318711937425;9804.558794854105;9836.852447213154;9925.702228933143;10114.988501149826;9976.850347244652;10040.397576145455;10042.297039207186;10101.343412678447;10109.949450252701 08:03:20 INFO - PROCESS | 2730 | __end_tp_report 08:03:20 INFO - PROCESS | 2730 | __start_cc_report 08:03:20 INFO - PROCESS | 2730 | _x_x_mozilla_cycle_collect,1052 08:03:20 INFO - PROCESS | 2730 | __end_cc_report 08:03:20 INFO - PROCESS | 2730 | __startTimestamp1464879800607__endTimestamp 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | ------- Summary: start ------- 08:03:20 INFO - PROCESS | 2730 | Number of tests: 6 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#0] dojo.html Cycles:165 Average:4690.69 Median:4800.45 stddev:495.19 (10.3%) stddev-sans-first:470.22 08:03:20 INFO - PROCESS | 2730 | Values: 2653.8 2657.1 2690.6 2707.5 2719.1 4626.1 4662.0 4690.7 4724.3 4783.9 4668.5 4670.0 4670.7 4696.0 4698.5 4095.4 4235.8 4324.6 4351.6 4447.1 4390.2 4426.9 4427.3 4447.3 4471.9 4227.8 4276.1 4313.4 4343.3 4357.2 4297.6 4303.4 4311.5 4353.7 4357.2 4322.4 4332.2 4354.7 4386.7 4396.4 4534.9 4619.8 4662.2 4666.0 4685.6 5106.8 5131.5 5136.2 5137.1 5137.2 5090.3 5139.7 5162.5 5165.2 5174.7 4615.3 4716.9 4757.4 4763.1 4782.6 5209.1 5234.7 5238.2 5291.2 5293.0 4806.7 5023.8 5071.0 5075.2 5075.7 5039.4 5072.1 5083.7 5090.6 5109.6 5091.2 5091.8 5093.3 5101.6 5144.4 4909.0 4915.1 5061.1 5068.8 5077.7 4282.9 4303.9 4307.0 4338.2 4349.9 3710.5 3727.9 3733.9 3767.3 3781.6 4266.3 4303.2 4307.3 4350.1 4354.7 5017.8 5029.3 5137.8 5168.6 5183.6 4438.4 4452.5 4471.2 4472.5 4489.3 4650.4 4663.0 4679.6 4680.1 4709.2 4685.8 4728.0 4737.1 4776.4 4792.9 5060.9 5092.7 5097.3 5101.6 5151.6 4792.6 4800.4 4805.6 4806.7 4839.1 4902.3 4944.8 4976.3 4994.2 5000.7 5031.1 5057.4 5068.2 5082.9 5116.3 4733.2 4923.2 4936.9 4943.0 4989.3 4884.1 4930.6 4934.5 4940.5 4965.8 4883.8 4885.4 4939.0 4939.1 4941.9 4871.4 4944.0 4996.7 5034.5 5054.7 4824.3 4855.6 4887.6 4893.7 4936.1 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#1] ext.html Cycles:180 Average:44793.74 Median:180896.38 stddev:74343.15 (41.1%) stddev-sans-first:74477.40 08:03:20 INFO - PROCESS | 2730 | Values: 525.4 591.3 594.6 596.0 608.4 27895.6 30725.4 30795.0 41487.0 42714.1 40288.6 40579.0 40981.6 40991.8 41012.0 7585.1 7766.1 7769.6 7784.7 7854.1 7469.7 9927.1 10276.8 10278.3 10629.1 10017.6 10093.2 10103.2 10106.8 10112.0 7469.8 7707.7 10193.5 10275.5 10295.1 7560.4 10041.3 10480.5 10504.5 10549.7 39442.0 39839.2 39880.4 39938.8 39997.2 3112.3 3156.4 3213.4 3504.8 3748.1 29752.3 30076.5 30195.8 30500.2 30546.7 3577.8 3583.9 3585.6 3613.4 3661.7 187021.0 187574.0 187574.0 187641.0 188117.0 56424.4 57377.7 57486.7 57520.7 57558.7 60136.7 60142.0 60214.7 60227.1 60287.0 14951.5 15463.7 15488.6 15498.6 15500.1 10180.2 10626.8 10666.5 11502.2 12017.2 17619.0 17622.6 17669.2 17675.9 17695.2 6100.4 6114.8 6177.5 6197.3 6201.9 849.9 953.5 1013.9 1040.3 1055.2 28039.9 28374.0 28764.0 28780.9 28806.6 964.7 995.8 1009.8 1057.6 1074.1 14820.6 15297.2 15302.4 15490.5 15641.6 357197.0 358215.0 361174.0 361346.0 361802.0 50702.5 50799.7 50813.0 50855.7 50907.0 1097.9 1142.4 1224.2 1227.0 1249.9 26327.3 29557.6 29592.4 29611.3 30169.8 43754.0 44393.6 44459.3 44518.0 44521.6 275305.0 275465.0 276062.0 276765.0 277196.0 31712.8 37358.3 37696.8 38234.2 38602.8 43487.8 43590.8 43949.6 43976.0 44985.0 37006.6 37009.4 37492.0 37680.0 37734.8 36549.3 36950.0 37247.6 37497.0 37681.0 36492.3 36615.8 36648.6 37120.3 37291.4 32061.5 32518.7 32939.0 32949.9 33308.5 25116.5 25428.5 25471.7 25477.5 25771.6 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#2] jquery.html Cycles:180 Average:22656.34 Median:25801.37 stddev:57700.43 (223.6%) stddev-sans-first:57838.04 08:03:20 INFO - PROCESS | 2730 | Values: 373.3 374.6 374.8 382.4 387.2 15681.7 16789.9 17642.8 17682.8 17687.5 11726.8 15681.5 16451.1 16483.4 17068.3 4414.2 4520.8 4540.0 4544.3 4545.5 3516.2 3637.8 4674.4 5157.5 5432.0 5469.7 5470.3 5492.3 5496.7 5500.2 5068.3 5360.4 5372.7 5424.4 5474.3 3364.9 4982.3 5265.3 5310.0 5325.4 19507.8 19824.1 20193.7 20333.0 20352.6 1780.4 1820.8 1887.3 1938.7 1963.2 8450.8 8519.7 8833.0 9059.0 9194.5 1704.3 1721.7 1723.3 1729.4 1756.3 65405.0 69072.7 70834.6 71312.0 72506.3 9932.4 9954.1 10107.5 10125.6 10262.5 13823.4 13960.7 13971.2 13974.0 13983.9 7478.1 7583.5 7679.2 7765.4 7830.4 7343.7 7344.7 7350.4 7503.2 7517.4 4336.0 4342.5 4356.5 4393.3 4400.7 1669.7 1692.3 1768.5 1810.2 1822.1 844.5 917.6 921.5 958.2 977.3 16002.0 16410.2 16667.0 16867.6 17025.0 872.3 885.3 912.4 940.5 946.3 9407.5 9463.3 9613.7 9650.1 9843.0 344076.0 344862.0 347763.0 350713.0 353173.0 71725.0 74285.6 74350.6 74470.0 74537.6 852.3 889.8 903.9 929.3 932.0 12772.8 15329.4 15526.1 15696.1 15828.5 34480.1 35293.5 35369.0 36027.5 36423.0 45293.0 45342.8 45929.5 46195.8 46928.3 7194.3 7225.1 7225.2 7299.7 7508.1 8026.8 9930.8 10436.1 10898.2 11214.9 9509.9 9522.8 9632.6 9793.7 9858.3 7706.0 9126.8 9449.8 9651.4 10648.8 5246.4 7865.8 9851.6 10330.6 10462.8 7519.2 7617.5 7661.1 7723.4 7935.8 8821.9 10389.0 10474.0 10783.7 10920.3 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#3] mootools.html Cycles:180 Average:5015.11 Median:5154.73 stddev:2775.54 (53.8%) stddev-sans-first:2768.56 08:03:20 INFO - PROCESS | 2730 | Values: 1206.2 1413.6 1423.4 1534.7 1765.6 6229.8 7372.0 7634.0 7646.4 7852.6 7603.4 7757.6 7761.4 7768.3 7769.3 2220.4 2252.3 2253.8 2256.4 2259.5 2176.3 2205.3 2216.3 2216.4 2221.9 2188.3 2190.7 2204.8 2209.2 2225.2 2156.5 2177.4 2181.9 2185.2 2186.5 2113.1 2115.1 2118.5 2118.9 2120.0 7491.9 7579.2 7637.7 7648.2 7651.0 4447.6 4604.8 4609.0 4616.1 4622.1 2774.0 2925.3 2977.3 2982.3 2993.2 918.4 961.6 971.5 974.2 999.7 10159.1 10185.7 10186.5 10270.9 10317.7 4088.5 4127.8 4172.7 4356.5 4364.7 8028.6 8065.8 8106.1 8117.4 8117.4 4497.6 4537.7 4540.7 4550.8 4558.3 3701.9 3839.5 4264.3 4270.3 4286.5 3467.1 3508.2 3536.6 3560.4 3589.1 1039.8 1041.0 1044.9 1045.9 1050.4 687.7 737.4 832.1 841.2 854.5 5187.3 5241.9 5255.9 5279.4 5450.4 400.3 407.3 408.3 409.5 410.1 3607.9 3634.3 3691.3 3743.8 3825.8 9097.5 9301.0 9377.6 9396.4 9412.8 9290.8 9390.3 9391.4 9397.5 9407.4 6922.8 7162.4 7170.8 7252.9 7254.4 5122.1 5211.9 5227.5 5240.3 5306.7 8497.4 8584.2 8838.2 8845.2 8857.8 8790.8 8814.6 8926.0 8927.9 8940.0 5417.3 5910.2 6073.2 6095.6 6221.4 7357.6 7371.7 7375.8 7380.0 7411.7 6621.5 6668.5 6739.3 6837.5 6899.7 6668.6 6683.1 6694.1 6743.3 6807.3 6246.7 6259.4 6260.7 6297.0 6335.1 4545.2 4592.3 4614.4 4667.3 4700.3 4609.3 4673.5 4691.3 4730.3 4767.7 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#4] prototype.html Cycles:180 Average:4163.44 Median:4606.68 stddev:1211.93 (26.3%) stddev-sans-first:1180.13 08:03:20 INFO - PROCESS | 2730 | Values: 299.6 302.7 310.1 314.6 318.1 4832.2 4878.2 4882.4 4913.6 4919.4 4806.5 4808.6 4827.0 4843.4 4845.0 3555.8 3678.9 3691.6 3693.6 3774.8 3678.4 3701.4 3809.3 3823.2 3861.6 3729.0 3767.6 3772.6 3775.5 3788.3 3531.2 3746.5 3771.9 3793.6 3799.5 3287.5 3288.7 3309.1 3309.7 3313.4 4642.1 4645.1 4666.3 4670.6 4687.1 4162.1 4308.2 4310.5 4319.6 4334.3 5058.3 5058.7 5064.9 5069.9 5071.5 3591.0 3593.9 3612.8 3622.3 3630.8 5062.6 5323.0 5338.7 5339.9 5365.5 3703.7 3704.0 3705.7 3714.3 3723.1 3764.7 3772.5 3779.8 3780.0 3788.5 4308.9 4328.6 4336.2 4343.6 4344.9 4925.3 5111.7 5132.9 5136.4 5172.5 1269.9 1289.8 1292.0 1296.1 1300.2 800.2 823.6 836.0 844.9 851.4 4432.6 4511.6 4518.1 4520.4 4542.2 5229.6 5236.2 5243.8 5252.8 5288.8 4451.9 4492.3 4495.9 4520.7 4522.1 2650.9 2815.4 2818.8 2820.1 2821.3 4960.3 4964.6 4964.7 4993.4 5006.4 5285.4 5362.8 5378.1 5416.1 5419.4 4988.6 5014.4 5014.8 5016.8 5022.3 5319.3 5321.8 5331.1 5332.6 5333.6 5021.4 5160.2 5234.9 5247.0 5250.6 4971.5 4991.6 5005.5 5008.7 5032.7 3385.8 3417.5 3429.6 3436.2 3442.0 4495.5 4701.7 4713.0 4771.4 4779.3 4550.5 4604.8 4632.9 4637.4 4668.7 4524.0 4562.3 4593.5 4623.3 4653.8 4332.6 4471.4 4474.6 4526.5 4527.9 4608.5 4908.5 5036.0 5181.9 5193.9 4965.4 4972.6 4973.2 4979.4 5001.5 08:03:20 INFO - PROCESS | 2730 | 08:03:20 INFO - PROCESS | 2730 | [#5] yui.html Cycles:180 Average:16598.48 Median:553.97 stddev:22399.50 (4043.5%) stddev-sans-first:22430.27 08:03:20 INFO - PROCESS | 2730 | Values: 638.9 643.8 657.7 741.1 760.4 12706.3 13697.5 13721.6 13724.1 13739.3 12205.6 12368.6 12382.1 12767.8 12859.1 633.6 790.0 800.1 800.5 802.4 778.1 797.2 804.7 806.5 807.8 849.8 851.3 851.3 852.9 853.2 660.9 822.3 848.9 849.0 849.5 614.2 623.7 623.8 623.9 624.1 12415.2 12453.4 12461.4 12472.0 12490.1 12709.3 13100.5 13228.3 13316.7 13589.3 11652.0 11882.3 11936.3 12007.8 12355.6 4221.5 4234.2 4272.7 4311.3 4398.0 61751.7 62474.4 63456.0 63542.0 63939.0 9617.3 9784.8 9857.7 10625.7 10699.5 18217.8 18397.2 18575.1 18628.3 18638.0 6718.6 6761.0 6840.7 6887.5 7001.3 3643.2 3871.2 3926.1 3940.1 4058.1 2966.5 3134.3 3136.0 3136.4 3140.0 553.3 557.8 564.3 566.8 567.6 501.5 580.3 600.2 620.8 631.6 8537.3 8994.2 9024.0 9058.4 9090.0 554.7 562.8 595.4 598.9 623.9 3894.9 3968.8 4060.0 4095.4 4099.9 81295.0 83715.6 91311.5 91400.5 91651.0 47625.3 65980.7 66158.7 67612.0 72950.6 66955.7 67025.3 69341.7 69375.0 71644.3 8912.5 9003.3 9026.1 9051.2 9059.6 31695.5 31810.5 38904.8 41963.6 42733.9 54989.7 55187.0 56781.7 61163.7 61796.7 10826.5 11017.0 11500.7 11674.2 11787.0 12559.3 12790.6 12872.7 13346.0 13476.6 7762.7 11671.6 11676.4 11952.8 12081.3 11920.6 12068.6 12259.6 12476.4 12645.6 11704.0 11963.6 12008.2 12313.9 12393.6 9691.3 9804.6 9836.9 9925.7 10115.0 9976.9 10040.4 10042.3 10101.3 10109.9 08:03:20 INFO - PROCESS | 2730 | -------- Summary: end -------- 08:03:20 INFO - PROCESS | 2730 | 08:03:21 INFO - TEST-INFO | 2730: exit 0 08:03:21 INFO - TEST-OK | dromaeo_css | took 1119124ms 08:03:21 INFO - TEST-START | kraken 08:03:21 INFO - operating with platform_type : linux_ 08:03:21 INFO - Initialising browser for kraken test... 08:03:21 INFO - TEST-INFO | started process 2976 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzuVDQn/profile http://localhost:59082/getInfo.html) 08:03:25 INFO - PROCESS | 2976 | __metrics Screen width/height:1600/1200 08:03:25 INFO - PROCESS | 2976 | colorDepth:24 08:03:25 INFO - PROCESS | 2976 | Browser inner width/height: 1024/697 08:03:25 INFO - PROCESS | 2976 | __metrics 08:03:26 ERROR - PROCESS | 2976 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:03:27 INFO - TEST-INFO | 2976: exit 0 08:03:27 INFO - Browser initialized. 08:03:27 INFO - Running cycle 1/1 for kraken test... 08:03:27 INFO - TEST-INFO | started process 3056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzuVDQn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:39 INFO - PROCESS | 3056 | RSS: Main: 158531584 08:03:39 INFO - PROCESS | 3056 | 08:03:42 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59082/tests/kraken/driver.html?testName=audio-beat-detection) 08:03:43 INFO - PROCESS | 3056 | RSS: Main: 215363584 08:03:43 INFO - PROCESS | 3056 | 08:03:46 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59082/tests/kraken/driver.html?testName=audio-dft) 08:03:46 INFO - PROCESS | 3056 | RSS: Main: 201732096 08:03:46 INFO - PROCESS | 3056 | 08:03:50 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59082/tests/kraken/driver.html?testName=audio-fft) 08:03:50 INFO - PROCESS | 3056 | RSS: Main: 270303232 08:03:50 INFO - PROCESS | 3056 | 08:03:52 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59082/tests/kraken/driver.html?testName=audio-oscillator) 08:03:53 INFO - PROCESS | 3056 | RSS: Main: 180723712 08:03:53 INFO - PROCESS | 3056 | 08:03:55 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59082/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:03:56 INFO - PROCESS | 3056 | RSS: Main: 184209408 08:03:56 INFO - PROCESS | 3056 | 08:03:59 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59082/tests/kraken/driver.html?testName=imaging-darkroom) 08:03:59 INFO - PROCESS | 3056 | RSS: Main: 220205056 08:03:59 INFO - PROCESS | 3056 | 08:04:03 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59082/tests/kraken/driver.html?testName=imaging-desaturate) 08:04:04 INFO - PROCESS | 3056 | RSS: Main: 216502272 08:04:04 INFO - PROCESS | 3056 | 08:04:07 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59082/tests/kraken/driver.html?testName=json-parse-financial) 08:04:07 INFO - PROCESS | 3056 | RSS: Main: 219451392 08:04:07 INFO - PROCESS | 3056 | 08:04:09 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59082/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:04:09 INFO - PROCESS | 3056 | RSS: Main: 208273408 08:04:09 INFO - PROCESS | 3056 | 08:04:11 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:04:12 INFO - PROCESS | 3056 | RSS: Main: 210661376 08:04:12 INFO - PROCESS | 3056 | 08:04:14 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:04:15 INFO - PROCESS | 3056 | RSS: Main: 216940544 08:04:15 INFO - PROCESS | 3056 | 08:04:18 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:04:18 INFO - PROCESS | 3056 | RSS: Main: 224317440 08:04:18 INFO - PROCESS | 3056 | 08:04:21 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:04:22 INFO - PROCESS | 3056 | RSS: Main: 225173504 08:04:22 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | Cycle 1(1): loaded http://localhost:59082/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59082/tests/kraken/driver.html?testName=ai-astar) 08:04:24 INFO - PROCESS | 3056 | RSS: Main: 267919360 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | __start_tp_report 08:04:24 INFO - PROCESS | 3056 | _x_x_mozilla_page_load 08:04:24 INFO - PROCESS | 3056 | _x_x_mozilla_page_load_details 08:04:24 INFO - PROCESS | 3056 | |i|pagename|runs| 08:04:24 INFO - PROCESS | 3056 | |0;ai-astar;116;110;116;115;115;116;114;116;119;115 08:04:24 INFO - PROCESS | 3056 | |1;audio-beat-detection;157;132;136;198;130;130;150;128;189;130 08:04:24 INFO - PROCESS | 3056 | |2;audio-dft;162;163;162;161;161;161;162;161;161;163 08:04:24 INFO - PROCESS | 3056 | |3;audio-fft;75;75;77;123;76;77;76;75;103;75 08:04:24 INFO - PROCESS | 3056 | |4;audio-oscillator;86;85;113;84;84;84;126;85;85;119 08:04:24 INFO - PROCESS | 3056 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;116;117 08:04:24 INFO - PROCESS | 3056 | |6;imaging-darkroom;146;146;146;147;146;148;146;146;146;145 08:04:24 INFO - PROCESS | 3056 | |7;imaging-desaturate;96;96;95;95;95;94;94;95;94;95 08:04:24 INFO - PROCESS | 3056 | |8;json-parse-financial;66;64;65;64;65;64;64;65;64;65 08:04:24 INFO - PROCESS | 3056 | |9;json-stringify-tinderbox;66;66;66;65;66;65;66;65;66;66 08:04:24 INFO - PROCESS | 3056 | |10;stanford-crypto-aes;84;84;88;84;87;86;85;93;114;85 08:04:24 INFO - PROCESS | 3056 | |11;stanford-crypto-ccm;144;147;152;130;131;128;136;138;152;142 08:04:24 INFO - PROCESS | 3056 | |12;stanford-crypto-pbkdf2;173;174;169;171;178;173;172;169;173;170 08:04:24 INFO - PROCESS | 3056 | |13;stanford-crypto-sha256-iterative;69;69;75;71;71;65;71;70;67;67 08:04:24 INFO - PROCESS | 3056 | __end_tp_report 08:04:24 INFO - PROCESS | 3056 | __start_cc_report 08:04:24 INFO - PROCESS | 3056 | _x_x_mozilla_cycle_collect,1921 08:04:24 INFO - PROCESS | 3056 | __end_cc_report 08:04:24 INFO - PROCESS | 3056 | __startTimestamp1464879864452__endTimestamp 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | ------- Summary: start ------- 08:04:24 INFO - PROCESS | 3056 | Number of tests: 14 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.25 (1.9%) stddev-sans-first:2.37 08:04:24 INFO - PROCESS | 3056 | Values: 116.0 110.0 116.0 115.0 115.0 116.0 114.0 116.0 119.0 115.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:143.00 stddev:25.90 (18.1%) stddev-sans-first:27.27 08:04:24 INFO - PROCESS | 3056 | Values: 157.0 132.0 136.0 198.0 130.0 130.0 150.0 128.0 189.0 130.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 08:04:24 INFO - PROCESS | 3056 | Values: 162.0 163.0 162.0 161.0 161.0 161.0 162.0 161.0 161.0 163.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.50 stddev:16.42 (21.7%) stddev-sans-first:17.14 08:04:24 INFO - PROCESS | 3056 | Values: 75.0 75.0 77.0 123.0 76.0 77.0 76.0 75.0 103.0 75.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:84.50 stddev:17.01 (20.1%) stddev-sans-first:17.72 08:04:24 INFO - PROCESS | 3056 | Values: 86.0 85.0 113.0 84.0 84.0 84.0 126.0 85.0 85.0 119.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:04:24 INFO - PROCESS | 3056 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 08:04:24 INFO - PROCESS | 3056 | Values: 146.0 146.0 146.0 147.0 146.0 148.0 146.0 146.0 146.0 145.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 08:04:24 INFO - PROCESS | 3056 | Values: 96.0 96.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.53 08:04:24 INFO - PROCESS | 3056 | Values: 66.0 64.0 65.0 64.0 65.0 64.0 64.0 65.0 64.0 65.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 08:04:24 INFO - PROCESS | 3056 | Values: 66.0 66.0 66.0 65.0 66.0 65.0 66.0 65.0 66.0 66.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:85.50 stddev:9.20 (10.8%) stddev-sans-first:9.58 08:04:24 INFO - PROCESS | 3056 | Values: 84.0 84.0 88.0 84.0 87.0 86.0 85.0 93.0 114.0 85.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:143.00 stddev:8.83 (6.2%) stddev-sans-first:9.25 08:04:24 INFO - PROCESS | 3056 | Values: 144.0 147.0 152.0 130.0 131.0 128.0 136.0 138.0 152.0 142.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:173.00 stddev:2.70 (1.6%) stddev-sans-first:2.85 08:04:24 INFO - PROCESS | 3056 | Values: 173.0 174.0 169.0 171.0 178.0 173.0 172.0 169.0 173.0 170.0 08:04:24 INFO - PROCESS | 3056 | 08:04:24 INFO - PROCESS | 3056 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.50 stddev:2.80 (4.0%) stddev-sans-first:2.96 08:04:24 INFO - PROCESS | 3056 | Values: 69.0 69.0 75.0 71.0 71.0 65.0 71.0 70.0 67.0 67.0 08:04:24 INFO - PROCESS | 3056 | -------- Summary: end -------- 08:04:24 INFO - PROCESS | 3056 | 08:04:25 INFO - TEST-INFO | 3056: exit 0 08:04:25 INFO - TEST-OK | kraken | took 64130ms 08:04:25 INFO - SUITE-END | took 1183s 08:04:25 INFO - Completed test suite (00:19:43) 08:04:25 INFO - javascript benchmark 08:04:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2653.81423300369, 2657.123149360711, 2690.609861570073, 2707.526182917989, 2719.0891051497747, 4626.097910907374, 4662.044280922411, 4690.695104818193, 4724.267738500534, 4783.856484305472, 4668.463126740426, 4669.953300466995, 4670.743109128996, 4696.0, 4698.506656801038, 4095.365218391154, 4235.830566777326, 4324.6107850293465, 4351.630111440516, 4447.110577884436, 4390.165868484994, 4426.889327766816, 4427.269500532424, 4447.288433850585, 4471.865844024668, 4227.76747278898, 4276.144771045803, 4313.374560688714, 4343.305071188594, 4357.237483440417, 4297.570242975708, 4303.419038429804, 4311.50417701962, 4353.651707863363, 4357.193919124972, 4322.37325587788, 4332.155229730204, 4354.716943398669, 4386.6929314947965, 4396.362527433536, 4534.9319760203625, 4619.792109355087, 4662.160811054009, 4666.043461090502, 4685.5782979532005, 5106.846794596131, 5131.53816156544, 5136.178211486182, 5137.126688462933, 5137.229415587654, 5090.287359769635, 5139.717315547643, 5162.5095615916425, 5165.173572228426, 5174.741262936838, 4615.284630882219, 4716.9056618867435, 4757.381540399795, 4763.071201115749, 4782.617390608743, 5209.062368773657, 5234.712090835041, 5238.214267859851, 5291.206319052173, 5293.0, 4806.663533552688, 5023.8492845214705, 5071.03650306448, 5075.187969924795, 5075.670081444694, 5039.370078740158, 5072.112380333427, 5083.694978301313, 5090.643654944119, 5109.642325037286, 5091.210862316347, 5091.770870310845, 5093.286939828353, 5101.566366858784, 5144.43411224765, 4908.975455122773, 4915.11527924977, 5061.063703214917, 5068.771905264272, 5077.746112694351, 4282.8715138545895, 4303.892402689895, 4306.956930430656, 4338.240807858612, 4349.891252718708, 3710.5361829771277, 3727.9254414912093, 3733.8879833605033, 3767.3407153747985, 3781.5840257571645, 4266.338717498793, 4303.182395344875, 4307.310830267143, 4350.0647360817575, 4354.738715677006, 5017.774200161001, 5029.321041659402, 5137.768800403991, 5168.638195326366, 5183.611229157828, 4438.356438316426, 4452.532484089189, 4471.217536931024, 4472.485664148665, 4489.304157855538, 4650.37219975299, 4662.95337046632, 4679.649026322972, 4680.110778951988, 4709.152352576569, 4685.765711714401, 4728.007118505084, 4737.076270127292, 4776.426828780569, 4792.856214313576, 5060.898782024413, 5092.668976516515, 5097.311862898461, 5101.617378696538, 5151.639385242997, 4792.568668819823, 4800.447948485829, 4805.6155507558615, 4806.735629540409, 4839.056384005085, 4902.264660300983, 4944.802207911644, 4976.303317535476, 4994.225894986283, 5000.699958002531, 5031.144705400164, 5057.367829021372, 5068.239764035424, 5082.949170508247, 5116.258142569232, 4733.242681170997, 4923.187674033888, 4936.851894443173, 4942.975285123552, 4989.326440930573, 4884.096442158212, 4930.580900623344, 4934.5312195341385, 4940.505949405175, 4965.8013679452415, 4883.8290659826735, 4885.389326334208, 4938.975305123451, 4939.061578300111, 4941.901161976813, 4871.439784424837, 4944.0, 4996.650234483696, 5034.496550345083, 5054.671446356122, 4824.276358546246, 4855.562999330077, 4887.633427492881, 4893.657443978887, 4936.111499930047], "value": 4659.278037852763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.3881256488883, 591.3140756722202, 594.5956749410399, 595.9523238140949, 608.3581821178658, 27895.581566276498, 30725.38549229014, 30795.0, 41487.0, 42714.14571708564, 40288.597114028926, 40579.0, 40981.59018409815, 40991.79504102483, 41012.0, 7585.089789225301, 7766.145723970359, 7769.611519424035, 7784.727534536292, 7854.057513098436, 7469.738559150431, 9927.106560409562, 10276.794464110713, 10278.331908425966, 10629.096526795232, 10017.649382271622, 10093.192544596415, 10103.191744660446, 10106.797864042715, 10112.0, 7469.813254668622, 7707.653155607955, 10193.490325483734, 10275.537600807982, 10295.124914382286, 7560.395168386516, 10041.347312424668, 10480.47597620116, 10504.474776261157, 10549.736256593647, 39442.0, 39839.203215935806, 39880.40179397311, 39938.80030599829, 39997.200055998714, 3112.2841746398453, 3156.447621666199, 3213.437648411542, 3504.842282097312, 3748.137928276484, 29752.256193595334, 30076.548851767242, 30195.849020754755, 30500.237494062825, 30546.69453305483, 3577.767445116059, 3583.8566457341676, 3585.64143585642, 3613.4218525035867, 3661.6704496595435, 187021.0, 187574.0, 187574.0, 187641.0, 188117.0, 56424.43575564274, 57377.71311143501, 57486.71256643774, 57520.71239643859, 57558.7122064387, 60136.69931650314, 60142.0, 60214.69892650596, 60227.096593551134, 60287.0, 14951.476698315506, 15463.690726185414, 15488.612784680248, 15498.612534686723, 15500.069995800062, 10180.23648226386, 10626.787464250672, 10666.520006599721, 11502.194846360675, 12017.15879888417, 17619.030953297693, 17622.559436014202, 17669.20488578017, 17675.911620441817, 17695.203715832817, 6100.3899610039525, 6114.7502485771965, 6177.536684748662, 6197.287311959182, 6201.937980620226, 849.9490030598184, 953.4851180362665, 1013.9188864890791, 1040.3237895368072, 1055.18223376882, 28039.859800700866, 28374.0, 28764.0, 28780.856095719806, 28806.567901481496, 964.7298756348234, 995.8307087795092, 1009.7627057641448, 1057.6139709006156, 1074.119222237773, 14820.555383338517, 15297.158656274012, 15302.387904483918, 15490.535283941499, 15641.60895977587, 357197.0, 358215.0, 361174.0, 361346.0, 361802.0, 50702.49297507051, 50799.746001269756, 50813.0, 50855.74572127116, 50907.0, 1097.8902109789117, 1142.4059489065805, 1224.1797995342963, 1227.0429065329058, 1249.925004499733, 26327.341816453976, 29557.55663665004, 29592.40815183641, 29611.259718507208, 30169.849150754108, 43754.0, 44393.55606443894, 44459.33311000273, 44518.0, 44521.55478445304, 275305.0, 275465.0, 276062.0, 276765.0, 277196.0, 31712.841435792674, 37358.3188756512, 37696.811515942245, 38234.23531529409, 38602.80698596489, 43487.78256108699, 43590.78204608957, 43949.56050439455, 43976.0, 44985.0, 37006.629933700315, 37009.444858326606, 37492.0, 37680.0, 37734.81132594429, 36549.26901462009, 36950.0, 37247.62752372442, 37497.0, 37681.0, 36492.27015459729, 36615.816920915226, 36648.63351366452, 37120.28797120375, 37291.44062839114, 32061.51907721433, 32518.748125187292, 32939.0, 32949.88247480153, 33308.500372493945, 25116.49767004613, 25428.491430171664, 25471.745282546937, 25477.490450191264, 25771.613425798252], "value": 18198.08949198017, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2665312660622, 374.58421152520697, 374.7751349190486, 382.4015415874155, 387.24100762505486, 15681.686366272668, 16789.916050419746, 17642.823571764293, 17682.82317176828, 17687.46937591871, 11726.824097638542, 15681.451149209779, 16451.095189764586, 16483.423080192195, 17068.317267309325, 4414.205443020255, 4520.796564154621, 4539.977300113512, 4544.272916333387, 4545.499995000548, 3516.226430185355, 3637.8362973666117, 4674.439067311927, 5157.48425157485, 5432.022235997519, 5469.699166545838, 5470.261514695525, 5492.258545096401, 5496.725163741817, 5500.1749737539485, 5068.265101560271, 5360.437154098802, 5372.7313634318325, 5424.430434804328, 5474.260974768415, 3364.8654053837818, 4982.252662100677, 5265.341832270966, 5310.0441920454305, 5325.4142044375085, 19507.80492195074, 19824.107915143853, 20193.697094543593, 20333.0, 20352.592948141104, 1780.4302623160593, 1820.8088150744245, 1887.3111314370294, 1938.6510428122933, 1963.1558429875147, 8450.830983380298, 8519.74440766778, 8833.0, 9058.954705226432, 9194.540272986324, 1704.3012364930319, 1721.7073097573443, 1723.2848367927295, 1729.3601367494107, 1756.2535922233, 65405.0, 69072.6546367265, 70834.64582677105, 71312.0, 72506.27493725048, 9932.404055756677, 9954.104130628133, 10107.545160467796, 10125.64560240388, 10262.538185781657, 13823.447062117402, 13960.650983725489, 13971.161730296215, 13974.0, 13983.86016139846, 7478.102627684712, 7583.5070720403, 7679.1936846631415, 7765.417593680497, 7830.373570114377, 7343.669534870944, 7344.669489872968, 7350.375218106414, 7503.174650788409, 7517.361024312994, 4335.956640433556, 4342.544032876565, 4356.542563030899, 4393.319035549495, 4400.691951563424, 1669.6577201673629, 1692.3146125819062, 1768.5136587438558, 1810.2216047099873, 1822.1162736072995, 844.5355054720002, 917.6329468212634, 921.5254144115786, 958.1759686669459, 977.296346630425, 16002.0, 16410.1794910254, 16667.0, 16867.578310542336, 17025.0, 872.3152325424511, 885.2519620920441, 912.4069354919222, 940.4780346907532, 946.3139224062637, 9407.4825884577, 9463.290253231035, 9613.663521776843, 9650.104968509419, 9842.950785246172, 344076.0, 344862.0, 347763.0, 350713.0, 353173.0, 71725.0, 74285.6285718568, 74350.62824685842, 74470.0, 74537.62731186418, 852.2968550945495, 889.822035592884, 903.8915330160423, 929.2612373163352, 932.021377553566, 12772.808407873703, 15329.38682452734, 15526.06843589343, 15696.136712480924, 15828.52514424569, 34480.137996550286, 35293.47059794157, 35369.0, 36027.459588105674, 36423.0, 45293.0, 45342.77328613336, 45929.540704592524, 46195.76902115468, 46928.29607555821, 7194.280571942973, 7225.096862892138, 7225.205227425085, 7299.671514781954, 7508.099028116442, 8026.8394632105865, 9930.801383972135, 10436.112930400697, 10898.237123401286, 11214.943925280322, 9509.857352139861, 9522.809543809224, 9632.614695412105, 9793.706188814347, 9858.260630452889, 7706.0, 9126.771830704285, 9449.811003780023, 9651.372660777028, 10648.84026739615, 5246.449122842047, 7865.842683146419, 9851.571522129394, 10330.638427654996, 10462.843057354297, 7519.210482899435, 7617.466777325534, 7661.080670319598, 7723.382129429745, 7935.769955656882, 8821.911780882108, 10389.0, 10474.0, 10783.676489705322, 10920.344779313267], "value": 7940.01295754248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1206.2340413837221, 1413.6324555615538, 1423.3950571007322, 1534.6777176792873, 1765.5674359781856, 6229.84425389365, 7371.963140184298, 7634.045744281965, 7646.350060244872, 7852.60736963151, 7603.391728661708, 7757.5733334666575, 7761.379089672827, 7768.262015108556, 7769.261920117594, 2220.4448887778094, 2252.256755270765, 2253.785890340423, 2256.3907744908865, 2259.491614386758, 2176.3035828534876, 2205.305328821424, 2216.2575537195057, 2216.4015715756736, 2221.9333419997347, 2188.26693057825, 2190.715207023085, 2204.7574766775642, 2209.1826024370926, 2225.2434172381386, 2156.5147841735643, 2177.42298290953, 2181.858179218362, 2185.2024011235862, 2186.529896072344, 2113.1019316790303, 2115.143366936378, 2118.5233322502468, 2118.851680382374, 2120.0, 7491.850162996764, 7579.1662917079075, 7637.656305466212, 7648.2351764823625, 7651.0, 4447.577480139414, 4604.769761511911, 4608.976955115203, 4616.053708989649, 4622.144903192887, 2774.0, 2925.283305590144, 2977.3152174999873, 2982.284251779579, 2993.161914663876, 918.3984490158956, 961.5721004153155, 971.4996776660025, 974.2108891797595, 999.6601155607055, 10159.13647339977, 10185.694429167137, 10186.490675466197, 10270.897291027144, 10317.742056448575, 4088.509378874554, 4127.752334859918, 4172.70791044624, 4356.455443069616, 4364.650827933821, 8028.638711258008, 8065.798355041054, 8106.148854370324, 8117.350611951029, 8117.391195660231, 4497.595216430537, 4537.659675524349, 4540.659450541222, 4550.840720574764, 4558.3162525620755, 3701.8889433317045, 3839.500864887547, 4264.253755592759, 4270.316749320094, 4286.485621725351, 3467.133216695826, 3508.1755787389943, 3536.6463353664944, 3560.4303311470558, 3589.066842620885, 1039.8336266197373, 1040.9635662751768, 1044.9059584637419, 1045.9215558833118, 1050.4327663062013, 687.7386593094592, 737.4248086492545, 832.1262674192075, 841.179849646602, 854.4616891358529, 5187.340051183585, 5241.868953276123, 5255.868603284872, 5279.366476022825, 5450.427705090988, 400.2914840731946, 407.27708317736256, 408.2997659014804, 409.5413137286258, 410.05073255413623, 3607.85568577254, 3634.309481198564, 3691.335559599298, 3743.756655817363, 3825.8087095645105, 9097.454152750724, 9301.0, 9377.578008989612, 9396.389234699722, 9412.811743765224, 9290.767730806649, 9390.295727820305, 9391.436513809193, 9397.530123493798, 9407.388519746195, 6922.7923162304205, 7162.355388015104, 7170.820729481805, 7252.927470725331, 7254.419646428273, 5122.129238029469, 5211.921821172761, 5227.529522343008, 5240.318758561362, 5306.6550674206055, 8497.362697797813, 8584.184502472255, 8838.160374764388, 8845.203931646183, 8857.778555536162, 8790.780230494289, 8814.603342849716, 8925.955370223368, 8927.866082008644, 8940.016598174325, 5417.349918009864, 5910.23166988306, 6073.240844894388, 6095.573309868267, 6221.440070393686, 7357.632118394166, 7371.705131794668, 7375.8156046099275, 7379.963100184465, 7411.66647500853, 6621.5364924454825, 6668.533202675961, 6739.292374300628, 6837.487188460985, 6899.689513971783, 6668.599884006975, 6683.097781799589, 6694.0628312037325, 6743.29195434492, 6807.285235050248, 6246.656433896179, 6259.405356491126, 6260.7495700171485, 6296.968515157578, 6335.113084168128, 4545.227311357011, 4592.311153326894, 4614.353990441408, 4667.346571479928, 4700.318453824245, 4609.308603709471, 4673.509281525527, 4691.272852707836, 4730.290456431425, 4767.713937163781], "value": 3988.8259788324235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6134985868229, 302.71393533111205, 310.1022539747432, 314.5973154362417, 318.1156385249007, 4832.173178690466, 4878.170710979129, 4882.413215704902, 4913.606911447085, 4919.4096708395, 4806.519348065201, 4808.615310775138, 4826.951730482694, 4843.418789745236, 4844.951550484494, 3555.822208889558, 3678.889633311005, 3691.6308369163003, 3693.580704638466, 3774.7923864187596, 3678.448232765093, 3701.370766969622, 3809.29528037314, 3823.1780167264033, 3861.5945325740813, 3728.9627103728885, 3767.585565587783, 3772.6038765929593, 3775.470434808685, 3788.299164654534, 3531.2054787672823, 3746.5129533160516, 3771.8679846205528, 3793.5637401698746, 3799.4680744695765, 3287.4904389819617, 3288.6516528223315, 3309.0900002499266, 3309.7352211822995, 3313.353895990283, 4642.117997580449, 4645.117427688762, 4666.346711460398, 4670.556297151765, 4687.1328804171335, 4162.146759914211, 4308.18144552534, 4310.547392523771, 4319.632831209351, 4334.263175260214, 5058.266551350033, 5058.671186372911, 5064.949350506485, 5069.8732531687, 5071.46749591295, 3590.982045089784, 3593.9101522462147, 3612.801295928723, 3622.2574372253625, 3630.7821530708243, 5062.620303477254, 5323.041852466555, 5338.733063346817, 5339.917432201444, 5365.490278423505, 3703.666669999659, 3704.036950392863, 3705.7220708446976, 3714.312852122312, 3723.1436769543157, 3764.698824094066, 3772.5472943246436, 3779.829907654162, 3779.981100094482, 3788.450674652215, 4308.892277693083, 4328.588784065509, 4336.154449882306, 4343.5656434356315, 4344.913101737948, 4925.335079764258, 5111.722069482629, 5132.923006154911, 5136.404516838228, 5172.482751724798, 1269.9174553653984, 1289.8065290206357, 1292.0, 1296.0797833538293, 1300.206873806977, 800.1518390506083, 823.6458322921081, 836.0135040651968, 844.8648216285364, 851.4082712514743, 4432.556744325541, 4511.593956543927, 4518.1415531049, 4520.434945631796, 4542.182407166676, 5229.555487783505, 5236.162214045735, 5243.8274843719, 5252.816151434681, 5288.81489147878, 4451.910961780811, 4492.303692927601, 4495.910081798345, 4520.7061541000385, 4522.140793249272, 2650.9204723858315, 2815.4087641595493, 2818.788590855653, 2820.083472871325, 2821.3369858083333, 4960.330355402047, 4964.553190212898, 4964.702117872867, 4993.350864387607, 5006.374203224597, 5285.445028271976, 5362.785488580491, 5378.139497680352, 5416.050221920105, 5419.403865574784, 4988.575971042429, 5014.373203349654, 5014.824481143142, 5016.774245158904, 5022.321986531844, 5319.281896943863, 5321.760520776497, 5331.1470164773455, 5332.573394128538, 5333.599980001438, 5021.447640759587, 5160.200168973815, 5234.947650523446, 5247.0, 5250.553702935294, 4971.453140154653, 4991.575716064175, 5005.549500544968, 5008.69947803133, 5032.672876263031, 3385.813780242012, 3417.487376893386, 3429.605595356466, 3436.1924947637785, 3441.967409777107, 4495.483019452674, 4701.672672027368, 4713.010267843654, 4771.403574553181, 4779.259214821708, 4550.544945505423, 4604.815807367668, 4632.9305060424795, 4637.39713837199, 4668.719876807402, 4524.0, 4562.315652652129, 4593.471750748707, 4623.329617205553, 4653.834817838084, 4332.6317263033, 4471.374007638999, 4474.574915383034, 4526.456825181, 4527.939787396793, 4608.539146085364, 4908.484609115992, 5036.010775743487, 5181.896362072813, 5193.909279051441, 4965.4041515018425, 4972.627052971114, 4973.229149481836, 4979.427365852974, 5001.549860512571], "value": 3781.3620885499845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.9297177310495, 643.7907680004, 657.7237560224709, 741.0958630470824, 760.3992845651934, 12706.301153436558, 13697.452101915937, 13721.588352349432, 13724.107932984374, 13739.313034348268, 12205.57280495183, 12368.628941131781, 12382.071344649165, 12767.808482872764, 12859.099863009593, 633.5628416392674, 790.036155889815, 800.1038836503095, 800.5476905548376, 802.4423025996934, 778.136268741699, 797.2067792546425, 804.6620419423856, 806.4757907360233, 807.7818988873, 849.8470275350435, 851.2934264560431, 851.3019324154166, 852.8763329317245, 853.2022558907411, 660.884345239586, 822.2599660305713, 848.9405741598091, 849.0024221539697, 849.4860609331328, 614.2322097378277, 623.688155922039, 623.8097380299007, 623.8752249550063, 624.1199908129515, 12415.193012454161, 12453.377331133406, 12461.376931153405, 12472.002239820793, 12490.12569120162, 12709.300988445631, 13100.541481048213, 13228.338583070808, 13316.667083322898, 13589.252591107484, 11652.0, 11882.28706277626, 11936.28382297065, 12007.759844803055, 12355.62933112008, 4221.493420789494, 4234.216669916075, 4272.743635381856, 4311.267084595626, 4397.9560204398185, 61751.69124154395, 62474.37525624731, 63456.0, 63542.0, 63939.0, 9617.326787124834, 9784.804303913881, 9857.704268871801, 10625.734356641145, 10699.465026748632, 18217.817821821878, 18397.172127254307, 18575.071246437623, 18628.254869805056, 18638.0, 6718.596884186867, 6761.0, 6840.726370945206, 6887.483438742114, 7001.29987001306, 3643.1620727232885, 3871.1870507193244, 3926.1362500249897, 3940.054386947112, 4058.066644671742, 2966.46603611352, 3134.341788224453, 3135.9686403135674, 3136.4197623439736, 3139.952900706491, 553.2641586689693, 557.8214971209175, 564.3397225246472, 566.7817890112254, 567.6310398241094, 501.5385845022588, 580.3413126101821, 600.2017316968421, 620.8292719502173, 631.6115588912786, 8537.274331681876, 8994.190522852974, 9024.0, 9058.411203271766, 9090.0, 554.6671996801887, 562.7664519224476, 595.3778301674761, 598.850287428143, 623.9344868788803, 3894.90262743428, 3968.8412463501713, 4059.959400405958, 4095.447114639545, 4099.877003689834, 81295.0, 83715.5814220925, 91311.54344228236, 91400.54299728591, 91651.0, 47625.28562071572, 65980.67009665017, 66158.66920665462, 67612.0, 72950.63524682343, 66955.66522167358, 67025.32974670191, 69341.65329173322, 69375.0, 71644.2835571648, 8912.465252084896, 9003.279737620844, 9026.142516460926, 9051.185393314634, 9059.637614495346, 31695.52456713105, 31810.522842156923, 38904.80547597244, 41963.580364195965, 42733.871935367955, 54989.72505137609, 55187.0, 56781.71609141928, 61163.69418152881, 61796.69101654463, 10826.458677065957, 11017.008469237808, 11500.654980350602, 11674.182807203415, 11787.0, 12559.309237991998, 12790.552330668754, 12872.742545149233, 13346.0, 13476.595702128952, 7762.68949242024, 11671.59149429766, 11676.416179191176, 11952.76094478088, 12081.275123492618, 11920.642380728592, 12068.649545555789, 12259.570915018288, 12476.376181191086, 12645.620631381073, 11704.0, 11963.581274655346, 12008.219465734699, 12313.876861231272, 12393.566225182076, 9691.318711937425, 9804.558794854105, 9836.852447213154, 9925.702228933143, 10114.988501149826, 9976.850347244652, 10040.397576145455, 10042.297039207186, 10101.343412678447, 10109.949450252701], "value": 6600.290645140932, "unit": "score"}], "name": "dromaeo_css", "value": 6373.516025049955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 116.0, 115.0, 115.0, 116.0, 114.0, 116.0, 119.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 132.0, 136.0, 198.0, 130.0, 130.0, 150.0, 128.0, 189.0, 130.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 161.0, 161.0, 161.0, 162.0, 161.0, 161.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 123.0, 76.0, 77.0, 76.0, 75.0, 103.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 113.0, 84.0, 84.0, 84.0, 126.0, 85.0, 85.0, 119.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 148.0, 146.0, 146.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 88.0, 84.0, 87.0, 86.0, 85.0, 93.0, 114.0, 85.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 152.0, 130.0, 131.0, 128.0, 136.0, 138.0, 152.0, 142.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 169.0, 171.0, 178.0, 173.0, 172.0, 169.0, 173.0, 170.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 75.0, 71.0, 71.0, 65.0, 71.0, 70.0, 67.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1562.0, "alertThreshold": 2.0}]} 08:04:25 INFO - Return code: 0 08:04:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:04:25 ERROR - # TBPL SUCCESS # 08:04:25 INFO - Running post-action listener: _resource_record_post_action 08:04:25 INFO - ##### 08:04:25 INFO - ##### Finished run-tests step (success) 08:04:25 INFO - ##### 08:04:25 INFO - Running post-run listener: _resource_record_post_run 08:04:25 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 522551296; Read time: 0; Write time: 387700 08:04:25 INFO - TinderboxPrint: CPU usage
13.2% 08:04:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:04:25 INFO - TinderboxPrint: I/O write bytes / time
522,551,296 / 387,700 08:04:25 INFO - TinderboxPrint: CPU idle
8,316.0 (86.8%) 08:04:25 INFO - TinderboxPrint: CPU user
1,217.8 (12.7%) 08:04:25 INFO - TinderboxPrint: Swap in / out
0 / 0 08:04:25 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237346816; Read time: 0; Write time: 222204 08:04:25 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 285204480; Read time: 0; Write time: 165496 08:04:26 INFO - Running post-run listener: _upload_blobber_files 08:04:26 INFO - Blob upload gear active. 08:04:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:04:26 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 08:04:26 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'] 08:04:26 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 08:04:26 INFO - (blobuploader) - INFO - Open directory for files ... 08:04:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:04:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:04:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:27 INFO - (blobuploader) - INFO - Done attempting. 08:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:04:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:31 INFO - (blobuploader) - INFO - Done attempting. 08:04:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:04:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:04:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:04:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:32 INFO - (blobuploader) - INFO - Done attempting. 08:04:32 INFO - (blobuploader) - INFO - Iteration through files over. 08:04:32 INFO - Return code: 0 08:04:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:04:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:04:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/88548c0a3d7f761aab477b7455c17eccb7e228edb8c71473468395a6940e869fcbea30e5fa29009e16db180069e8331e5d6efdf959b83d517cec8a9947e004b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f9a31c4d7ede38030a5a6dbfeb750c8afc33b30138eac52978abbdf0e0d300cb01310d732d0759c75d7b0613585341d49a244b1e48ecc106c4adf25929652a2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/638905382da697968673a7ef788f3c53e2593d844f1e4fc26184504779cbe0f3151ef0ceb3ec8261f06f8c86ef2f3ef11bc42ecfe0598080f79cd3942050d6aa"} 08:04:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:04:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:04:32 INFO - Contents: 08:04:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/88548c0a3d7f761aab477b7455c17eccb7e228edb8c71473468395a6940e869fcbea30e5fa29009e16db180069e8331e5d6efdf959b83d517cec8a9947e004b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f9a31c4d7ede38030a5a6dbfeb750c8afc33b30138eac52978abbdf0e0d300cb01310d732d0759c75d7b0613585341d49a244b1e48ecc106c4adf25929652a2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/638905382da697968673a7ef788f3c53e2593d844f1e4fc26184504779cbe0f3151ef0ceb3ec8261f06f8c86ef2f3ef11bc42ecfe0598080f79cd3942050d6aa"} 08:04:32 INFO - Running post-run listener: copy_logs_to_upload_dir 08:04:32 INFO - Copying logs to upload dir... 08:04:32 INFO - mkdir: /builds/slave/test/build/upload/logs 08:04:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.720051 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-06-02 08:04:32.011454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:04:32.015144) ========= 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=4300f881736b422476174874000001bc-1464878626.116488-273983417 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/88548c0a3d7f761aab477b7455c17eccb7e228edb8c71473468395a6940e869fcbea30e5fa29009e16db180069e8331e5d6efdf959b83d517cec8a9947e004b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f9a31c4d7ede38030a5a6dbfeb750c8afc33b30138eac52978abbdf0e0d300cb01310d732d0759c75d7b0613585341d49a244b1e48ecc106c4adf25929652a2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/638905382da697968673a7ef788f3c53e2593d844f1e4fc26184504779cbe0f3151ef0ceb3ec8261f06f8c86ef2f3ef11bc42ecfe0598080f79cd3942050d6aa"} build_url:https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011453 build_url: 'https://queue.taskcluster.net/v1/task/G08sFrTDTomtyTddqBuLHQ/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/88548c0a3d7f761aab477b7455c17eccb7e228edb8c71473468395a6940e869fcbea30e5fa29009e16db180069e8331e5d6efdf959b83d517cec8a9947e004b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f9a31c4d7ede38030a5a6dbfeb750c8afc33b30138eac52978abbdf0e0d300cb01310d732d0759c75d7b0613585341d49a244b1e48ecc106c4adf25929652a2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/638905382da697968673a7ef788f3c53e2593d844f1e4fc26184504779cbe0f3151ef0ceb3ec8261f06f8c86ef2f3ef11bc42ecfe0598080f79cd3942050d6aa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:04:32.041051) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:04:32.041383) ========= 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=4300f881736b422476174874000001bc-1464878626.116488-273983417 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:04:32.124585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:04:32.124915) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:04:32.125203) ========= ========= Total master_lag: 0.17 =========