builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1464571337.77 results: success (0) buildid: 20160529173637 builduid: 3c631515640a438ab8acb6c772b83c9c revision: 1cafe268e0149021d977aca6bf5af1478e52324d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.766187) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.766744) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.767085) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.838627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.838948) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-29 18:22:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-05-29 18:22:19 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090071 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.977378) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:17.977740) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019412 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.018566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.018929) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1cafe268e0149021d977aca6bf5af1478e52324d --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 1cafe268e0149021d977aca6bf5af1478e52324d --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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-29 18:22:19,714 truncating revision to first 12 chars 2016-05-29 18:22:19,714 Setting DEBUG logging. 2016-05-29 18:22:19,714 attempt 1/10 2016-05-29 18:22:19,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1cafe268e014?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-29 18:22:20,026 unpacking tar archive at: fx-team-1cafe268e014/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662632 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.700003) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.700401) ========= script_repo_revision: 1cafe268e0149021d977aca6bf5af1478e52324d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.700918) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.701503) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 18:22:18.733456) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-29 18:22:18.733779) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:20 INFO - MultiFileLogger online at 20160529 18:22:20 in /builds/slave/test 18:22:20 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 18:22:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:20 INFO - {'append_to_log': False, 18:22:20 INFO - 'base_work_dir': '/builds/slave/test', 18:22:20 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:22:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:20 INFO - 'branch': 'Fx-Team-Non-PGO', 18:22:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:20 INFO - 'config_files': ('talos/linux_config.py',), 18:22:20 INFO - 'default_actions': ('clobber', 18:22:20 INFO - 'read-buildbot-config', 18:22:20 INFO - 'download-and-extract', 18:22:20 INFO - 'populate-webroot', 18:22:20 INFO - 'create-virtualenv', 18:22:20 INFO - 'install', 18:22:20 INFO - 'run-tests'), 18:22:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:20 INFO - 'download_minidump_stackwalk': True, 18:22:20 INFO - 'download_symbols': 'ondemand', 18:22:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:20 INFO - 'installer_path': 'installer.exe', 18:22:20 INFO - 'log_level': 'info', 18:22:20 INFO - 'log_name': 'talos', 18:22:20 INFO - 'log_to_console': True, 18:22:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:20 INFO - 'opt_config_files': (), 18:22:20 INFO - 'pip_index': False, 18:22:20 INFO - 'sps_profile': False, 18:22:20 INFO - 'sps_profile_interval': 0, 18:22:20 INFO - 'suite': 'dromaeojs', 18:22:20 INFO - 'system_bits': '32', 18:22:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:20 INFO - 'title': 'talos-linux64-ix-006', 18:22:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:20 INFO - 'use_talos_json': True, 18:22:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:20 INFO - 'work_dir': 'build'} 18:22:20 INFO - ##### 18:22:20 INFO - ##### Running clobber step. 18:22:20 INFO - ##### 18:22:20 INFO - Running pre-action listener: _resource_record_pre_action 18:22:20 INFO - Running main action method: clobber 18:22:20 INFO - rmtree: /builds/slave/test/build 18:22:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:21 INFO - Running post-action listener: _resource_record_post_action 18:22:21 INFO - ##### 18:22:21 INFO - ##### Finished clobber step (success) 18:22:21 INFO - ##### 18:22:21 INFO - ##### 18:22:21 INFO - ##### Running read-buildbot-config step. 18:22:21 INFO - ##### 18:22:21 INFO - Running pre-action listener: _resource_record_pre_action 18:22:21 INFO - Running main action method: read_buildbot_config 18:22:21 INFO - Using buildbot properties: 18:22:21 INFO - { 18:22:21 INFO - "project": "", 18:22:21 INFO - "product": "firefox", 18:22:21 INFO - "script_repo_revision": "production", 18:22:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:22:21 INFO - "repository": "", 18:22:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:22:21 INFO - "buildid": "20160529173637", 18:22:21 INFO - "pgo_build": "False", 18:22:21 INFO - "basedir": "/builds/slave/test", 18:22:21 INFO - "buildnumber": 1800, 18:22:21 INFO - "slavename": "talos-linux64-ix-006", 18:22:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:22:21 INFO - "platform": "ubuntu64_hw", 18:22:21 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:22:21 INFO - "branch": "fx-team", 18:22:21 INFO - "repo_path": "integration/fx-team", 18:22:21 INFO - "revision": "1cafe268e0149021d977aca6bf5af1478e52324d", 18:22:21 INFO - "stage_platform": "linux64", 18:22:21 INFO - "builduid": "3c631515640a438ab8acb6c772b83c9c", 18:22:21 INFO - "slavebuilddir": "test" 18:22:21 INFO - } 18:22:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:22:21 INFO - Running post-action listener: _resource_record_post_action 18:22:21 INFO - ##### 18:22:21 INFO - ##### Finished read-buildbot-config step (success) 18:22:21 INFO - ##### 18:22:21 INFO - ##### 18:22:21 INFO - ##### Running download-and-extract step. 18:22:21 INFO - ##### 18:22:21 INFO - Running pre-action listener: _resource_record_pre_action 18:22:21 INFO - Running main action method: download_and_extract 18:22:21 INFO - mkdir: /builds/slave/test/build/tests 18:22: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')]} 18:22:21 INFO - https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:22:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:22:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:22:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:29 INFO - Downloaded 1361 bytes. 18:22:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:22:29 INFO - Using the following test package requirements: 18:22:29 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:22:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:22:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'jsshell-linux-x86_64.zip'], 18:22:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:22:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:22:29 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:22:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:22:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:29 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:22:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:29 INFO - https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:38 INFO - Downloaded 22837897 bytes. 18:22:38 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'] 18:22:38 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 18:22:39 INFO - Return code: 0 18:22:39 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 18:22:39 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')]} 18:22:39 INFO - https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:22:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:22:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:22:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:41 INFO - Downloaded 22837897 bytes. 18:22:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:22:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:22:42 INFO - Return code: 0 18:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:42 INFO - https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:22:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:22:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:22:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:44 INFO - Downloaded 10834034 bytes. 18:22:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:22:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:22:44 INFO - Return code: 0 18:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:44 INFO - https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:22:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:22:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:22:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CssibVonT1Cd0H_NhwaPPg/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 18:22:45 INFO - Downloaded 54852112 bytes. 18:22:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:22:45 INFO - mkdir: /builds/slave/test/properties 18:22:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:45 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:45 INFO - Contents: 18:22:45 INFO - build_url:https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:22:46 INFO - Running post-action listener: _resource_record_post_action 18:22:46 INFO - Running post-action listener: set_extra_try_arguments 18:22:46 INFO - ##### 18:22:46 INFO - ##### Finished download-and-extract step (success) 18:22:46 INFO - ##### 18:22:46 INFO - ##### 18:22:46 INFO - ##### Running populate-webroot step. 18:22:46 INFO - ##### 18:22:46 INFO - Running pre-action listener: _resource_record_pre_action 18:22:46 INFO - Running main action method: populate_webroot 18:22:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:46 INFO - '--tppagecycles', 18:22:46 INFO - '7'], 18:22:46 INFO - 'tests': ['tsvgm']}}, 18:22:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['damp', 'tps']}, 18:22:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'tests': ['damp', 'tps']}, 18:22:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['dromaeo_dom']}, 18:22:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['a11yr', 18:22:46 INFO - 'ts_paint', 18:22:46 INFO - 'tpaint', 18:22:46 INFO - 'sessionrestore', 18:22:46 INFO - 'sessionrestore_no_auto_restore', 18:22:46 INFO - 'tabpaint']}, 18:22:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['tsvgx', 18:22:46 INFO - 'tsvgr_opacity', 18:22:46 INFO - 'tart', 18:22:46 INFO - 'tscrollx', 18:22:46 INFO - 'cart']}, 18:22:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:46 INFO - 'tsvgr_opacity', 18:22:46 INFO - 'tart', 18:22:46 INFO - 'tscrollx', 18:22:46 INFO - 'cart']}, 18:22:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'talos_options': ['--disable-e10s'], 18:22:46 INFO - 'tests': ['tp5o']}, 18:22:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'tests': ['tp5o']}, 18:22:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'talos_options': ['--disable-e10s', 18:22:46 INFO - '--xperf_path', 18:22:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:46 INFO - 'tests': ['tp5n']}, 18:22:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:46 INFO - 'talos_options': ['--xperf_path', 18:22:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:46 INFO - 'tests': ['tp5n']}}, 18:22:46 INFO - 'talos.zip': {'path': '', 18:22:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:46 INFO - Running post-action listener: _resource_record_post_action 18:22:46 INFO - ##### 18:22:46 INFO - ##### Finished populate-webroot step (success) 18:22:46 INFO - ##### 18:22:46 INFO - ##### 18:22:46 INFO - ##### Running create-virtualenv step. 18:22:46 INFO - ##### 18:22:46 INFO - Running pre-action listener: _resource_record_pre_action 18:22:46 INFO - Running main action method: create_virtualenv 18:22:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:46 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 18:22:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:46 INFO - Using real prefix '/usr' 18:22:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:49 INFO - Installing pip.................done. 18:22:49 INFO - Return code: 0 18:22:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:22:49 INFO - Using env: {'DISPLAY': ':0', 18:22:49 INFO - 'HOME': '/home/cltbld', 18:22:49 INFO - 'LANG': 'en_US.UTF-8', 18:22:49 INFO - 'LANGUAGE': 'en_US:en', 18:22:49 INFO - 'LOGNAME': 'cltbld', 18:22:49 INFO - 'MAIL': '/var/mail/cltbld', 18:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:49 INFO - 'MOZ_NO_REMOTE': '1', 18:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:49 INFO - 'NO_EM_RESTART': '1', 18:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:49 INFO - 'PWD': '/builds/slave/test', 18:22:49 INFO - 'SHELL': '/bin/bash', 18:22:49 INFO - 'SHLVL': '1', 18:22:49 INFO - 'TERM': 'linux', 18:22:49 INFO - 'TMOUT': '86400', 18:22:49 INFO - 'USER': 'cltbld', 18:22:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:49 INFO - '_': '/tools/buildbot/bin/python'} 18:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:22:49 INFO - Cleaning up... 18:22:49 INFO - Return code: 0 18:22:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:22:49 INFO - Using env: {'DISPLAY': ':0', 18:22:49 INFO - 'HOME': '/home/cltbld', 18:22:49 INFO - 'LANG': 'en_US.UTF-8', 18:22:49 INFO - 'LANGUAGE': 'en_US:en', 18:22:49 INFO - 'LOGNAME': 'cltbld', 18:22:49 INFO - 'MAIL': '/var/mail/cltbld', 18:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:49 INFO - 'MOZ_NO_REMOTE': '1', 18:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:49 INFO - 'NO_EM_RESTART': '1', 18:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:49 INFO - 'PWD': '/builds/slave/test', 18:22:49 INFO - 'SHELL': '/bin/bash', 18:22:49 INFO - 'SHLVL': '1', 18:22:49 INFO - 'TERM': 'linux', 18:22:49 INFO - 'TMOUT': '86400', 18:22:49 INFO - 'USER': 'cltbld', 18:22:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:49 INFO - '_': '/tools/buildbot/bin/python'} 18:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:49 INFO - Downloading/unpacking psutil>=3.1.1 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:51 INFO - Installing collected packages: psutil 18:22:51 INFO - Running setup.py install for psutil 18:22:51 INFO - building 'psutil._psutil_linux' extension 18:22:51 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 18:22:51 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 18:22:51 INFO - building 'psutil._psutil_posix' extension 18:22:51 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 18:22:51 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 18:22:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:52 INFO - Successfully installed psutil 18:22:52 INFO - Cleaning up... 18:22:52 INFO - Return code: 0 18:22:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:22:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:22:52 INFO - Using env: {'DISPLAY': ':0', 18:22:52 INFO - 'HOME': '/home/cltbld', 18:22:52 INFO - 'LANG': 'en_US.UTF-8', 18:22:52 INFO - 'LANGUAGE': 'en_US:en', 18:22:52 INFO - 'LOGNAME': 'cltbld', 18:22:52 INFO - 'MAIL': '/var/mail/cltbld', 18:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:52 INFO - 'MOZ_NO_REMOTE': '1', 18:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:52 INFO - 'NO_EM_RESTART': '1', 18:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:52 INFO - 'PWD': '/builds/slave/test', 18:22:52 INFO - 'SHELL': '/bin/bash', 18:22:52 INFO - 'SHLVL': '1', 18:22:52 INFO - 'TERM': 'linux', 18:22:52 INFO - 'TMOUT': '86400', 18:22:52 INFO - 'USER': 'cltbld', 18:22:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:52 INFO - '_': '/tools/buildbot/bin/python'} 18:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:54 INFO - Installing collected packages: mozsystemmonitor 18:22:54 INFO - Running setup.py install for mozsystemmonitor 18:22:54 INFO - Successfully installed mozsystemmonitor 18:22:54 INFO - Cleaning up... 18:22:54 INFO - Return code: 0 18:22:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:54 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')]} 18:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:54 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')]} 18:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:54 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 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:22:54 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 18:22:54 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 18:22:54 INFO - Using env: {'DISPLAY': ':0', 18:22:54 INFO - 'HOME': '/home/cltbld', 18:22:54 INFO - 'LANG': 'en_US.UTF-8', 18:22:54 INFO - 'LANGUAGE': 'en_US:en', 18:22:54 INFO - 'LOGNAME': 'cltbld', 18:22:54 INFO - 'MAIL': '/var/mail/cltbld', 18:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:54 INFO - 'MOZ_NO_REMOTE': '1', 18:22:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:54 INFO - 'NO_EM_RESTART': '1', 18:22:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:54 INFO - 'PWD': '/builds/slave/test', 18:22:54 INFO - 'SHELL': '/bin/bash', 18:22:54 INFO - 'SHLVL': '1', 18:22:54 INFO - 'TERM': 'linux', 18:22:54 INFO - 'TMOUT': '86400', 18:22:54 INFO - 'USER': 'cltbld', 18:22:54 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:54 INFO - '_': '/tools/buildbot/bin/python'} 18:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:54 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 18:22:54 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 18:22:54 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 18:22:54 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 18:22:54 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 18:22:54 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 18:22:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:56 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 18:22:57 INFO - Downloading docopt-0.6.1.tar.gz 18:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:57 INFO - Running setup.py install for blobuploader 18:22:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:57 INFO - Running setup.py install for requests 18:22:57 INFO - Running setup.py install for docopt 18:22:57 INFO - Successfully installed blobuploader requests docopt 18:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:57 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')]} 18:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 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')]} 18:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:57 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 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:22:57 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 18:22:57 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 18:22:57 INFO - Using env: {'DISPLAY': ':0', 18:22:57 INFO - 'HOME': '/home/cltbld', 18:22:57 INFO - 'LANG': 'en_US.UTF-8', 18:22:57 INFO - 'LANGUAGE': 'en_US:en', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MAIL': '/var/mail/cltbld', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SHLVL': '1', 18:22:57 INFO - 'TERM': 'linux', 18:22:57 INFO - 'TMOUT': '86400', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - '_': '/tools/buildbot/bin/python'} 18:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:58 INFO - Running setup.py (path:/tmp/pip-VXwUIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:58 INFO - Running setup.py (path:/tmp/pip-X9lpZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:58 INFO - Running setup.py (path:/tmp/pip-NdOnWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:58 INFO - Running setup.py (path:/tmp/pip-4Dkwx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:58 INFO - Running setup.py (path:/tmp/pip-VvjUu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:58 INFO - Running setup.py (path:/tmp/pip-6ERP5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:58 INFO - Running setup.py (path:/tmp/pip-PDAZYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:58 INFO - Running setup.py (path:/tmp/pip-ojE4l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:58 INFO - Running setup.py (path:/tmp/pip-KpHcoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:58 INFO - Running setup.py (path:/tmp/pip-OKHW3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:58 INFO - Running setup.py (path:/tmp/pip-LOISG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:58 INFO - Running setup.py (path:/tmp/pip-0IKrU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:58 INFO - Running setup.py (path:/tmp/pip-mBk8ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:59 INFO - Running setup.py (path:/tmp/pip-ApJVG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:59 INFO - Running setup.py (path:/tmp/pip-r7d_41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:59 INFO - Running setup.py (path:/tmp/pip-QN52Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:59 INFO - Running setup.py (path:/tmp/pip-xrBj0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:59 INFO - Running setup.py install for manifestparser 18:22:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:59 INFO - Running setup.py install for mozcrash 18:22:59 INFO - Running setup.py install for mozdebug 18:22:59 INFO - Running setup.py install for mozdevice 18:22:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:22:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:59 INFO - Running setup.py install for mozfile 18:22:59 INFO - Running setup.py install for mozhttpd 18:22:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:59 INFO - Running setup.py install for mozinfo 18:22:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for mozInstall 18:23:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for mozleak 18:23:00 INFO - Running setup.py install for mozlog 18:23:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for moznetwork 18:23:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for mozprocess 18:23:00 INFO - Running setup.py install for mozprofile 18:23:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for mozrunner 18:23:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:23:00 INFO - Running setup.py install for mozscreenshot 18:23:00 INFO - Running setup.py install for moztest 18:23:00 INFO - Running setup.py install for mozversion 18:23:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:23:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:23:01 INFO - Cleaning up... 18:23:01 INFO - Return code: 0 18:23:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:23:01 INFO - Using env: {'DISPLAY': ':0', 18:23:01 INFO - 'HOME': '/home/cltbld', 18:23:01 INFO - 'LANG': 'en_US.UTF-8', 18:23:01 INFO - 'LANGUAGE': 'en_US:en', 18:23:01 INFO - 'LOGNAME': 'cltbld', 18:23:01 INFO - 'MAIL': '/var/mail/cltbld', 18:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:01 INFO - 'MOZ_NO_REMOTE': '1', 18:23:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:01 INFO - 'NO_EM_RESTART': '1', 18:23:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:01 INFO - 'PWD': '/builds/slave/test', 18:23:01 INFO - 'SHELL': '/bin/bash', 18:23:01 INFO - 'SHLVL': '1', 18:23:01 INFO - 'TERM': 'linux', 18:23:01 INFO - 'TMOUT': '86400', 18:23:01 INFO - 'USER': 'cltbld', 18:23:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:01 INFO - '_': '/tools/buildbot/bin/python'} 18:23:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:23:01 INFO - Running setup.py (path:/tmp/pip-HKF6WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:23:01 INFO - Running setup.py (path:/tmp/pip-TxZmPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:23:01 INFO - Running setup.py (path:/tmp/pip-tng8xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:23:01 INFO - Running setup.py (path:/tmp/pip-oFnBGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:23:01 INFO - Running setup.py (path:/tmp/pip-BVlLBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:23:01 INFO - Running setup.py (path:/tmp/pip-DgnMmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:23:01 INFO - Running setup.py (path:/tmp/pip-s92GdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:23:01 INFO - Running setup.py (path:/tmp/pip-ACitQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:23:01 INFO - Running setup.py (path:/tmp/pip-QtFh1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:23:01 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)) 18:23:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:23:01 INFO - Running setup.py (path:/tmp/pip-N3M94o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:23:02 INFO - Running setup.py (path:/tmp/pip-JDRqCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:23:02 INFO - Running setup.py (path:/tmp/pip-Bd7W0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:23:02 INFO - Running setup.py (path:/tmp/pip-QWTfhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:23:02 INFO - Running setup.py (path:/tmp/pip-HXEmvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:02 INFO - Running setup.py (path:/tmp/pip-pcF1FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:23:02 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)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:23:02 INFO - Running setup.py (path:/tmp/pip-9joqij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:23:02 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)) 18:23:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:23:02 INFO - Running setup.py (path:/tmp/pip-3morgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:23:02 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)) 18:23:02 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)) 18:23:02 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)) 18:23:02 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)) 18:23:02 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)) 18:23:02 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)) 18:23:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:23:02 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 18:23:02 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 18:23:02 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 18:23:02 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 18:23:02 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 18:23:02 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 18:23:04 INFO - Downloading blessings-1.6.tar.gz 18:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:23:04 INFO - Installing collected packages: blessings 18:23:04 INFO - Running setup.py install for blessings 18:23:04 INFO - Successfully installed blessings 18:23:04 INFO - Cleaning up... 18:23:04 INFO - Return code: 0 18:23:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:23:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:04 INFO - Reading from file tmpfile_stdout 18:23:04 INFO - Current package versions: 18:23:04 INFO - argparse == 1.2.1 18:23:04 INFO - blessings == 1.6 18:23:04 INFO - blobuploader == 1.2.4 18:23:04 INFO - docopt == 0.6.1 18:23:04 INFO - manifestparser == 1.1 18:23:04 INFO - mozInstall == 1.12 18:23:04 INFO - mozcrash == 0.17 18:23:04 INFO - mozdebug == 0.1 18:23:04 INFO - mozdevice == 0.48 18:23:04 INFO - mozfile == 1.2 18:23:04 INFO - mozhttpd == 0.7 18:23:04 INFO - mozinfo == 0.9 18:23:04 INFO - mozleak == 0.1 18:23:04 INFO - mozlog == 3.2 18:23:04 INFO - moznetwork == 0.27 18:23:04 INFO - mozprocess == 0.22 18:23:04 INFO - mozprofile == 0.28 18:23:04 INFO - mozrunner == 6.11 18:23:04 INFO - mozscreenshot == 0.1 18:23:04 INFO - mozsystemmonitor == 0.3 18:23:04 INFO - moztest == 0.7 18:23:04 INFO - mozversion == 1.4 18:23:04 INFO - psutil == 3.1.1 18:23:04 INFO - requests == 1.2.3 18:23:04 INFO - wsgiref == 0.1.2 18:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:04 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 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:23:04 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 18:23:04 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 18:23:04 INFO - Using env: {'DISPLAY': ':0', 18:23:04 INFO - 'HOME': '/home/cltbld', 18:23:04 INFO - 'LANG': 'en_US.UTF-8', 18:23:04 INFO - 'LANGUAGE': 'en_US:en', 18:23:04 INFO - 'LOGNAME': 'cltbld', 18:23:04 INFO - 'MAIL': '/var/mail/cltbld', 18:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:04 INFO - 'MOZ_NO_REMOTE': '1', 18:23:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:04 INFO - 'NO_EM_RESTART': '1', 18:23:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:04 INFO - 'PWD': '/builds/slave/test', 18:23:04 INFO - 'SHELL': '/bin/bash', 18:23:04 INFO - 'SHLVL': '1', 18:23:04 INFO - 'TERM': 'linux', 18:23:04 INFO - 'TMOUT': '86400', 18:23:04 INFO - 'USER': 'cltbld', 18:23:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:04 INFO - '_': '/tools/buildbot/bin/python'} 18:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:23:05 INFO - Cleaning up... 18:23:05 INFO - Return code: 0 18:23:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:23: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')]} 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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')]} 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:23:05 INFO - Using env: {'DISPLAY': ':0', 18:23:05 INFO - 'HOME': '/home/cltbld', 18:23:05 INFO - 'LANG': 'en_US.UTF-8', 18:23:05 INFO - 'LANGUAGE': 'en_US:en', 18:23:05 INFO - 'LOGNAME': 'cltbld', 18:23:05 INFO - 'MAIL': '/var/mail/cltbld', 18:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:05 INFO - 'MOZ_NO_REMOTE': '1', 18:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:05 INFO - 'NO_EM_RESTART': '1', 18:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:05 INFO - 'PWD': '/builds/slave/test', 18:23:05 INFO - 'SHELL': '/bin/bash', 18:23:05 INFO - 'SHLVL': '1', 18:23:05 INFO - 'TERM': 'linux', 18:23:05 INFO - 'TMOUT': '86400', 18:23:05 INFO - 'USER': 'cltbld', 18:23:05 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:05 INFO - '_': '/tools/buildbot/bin/python'} 18:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:05 INFO - Downloading/unpacking jsonschema 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:23:07 INFO - Downloading/unpacking functools32 (from jsonschema) 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 INFO - Downloading functools32-3.2.3-2.tar.gz 18:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:23:07 INFO - warning: no files found matching '*.txt' 18:23:07 INFO - no previously-included directories found matching 'build' 18:23:07 INFO - no previously-included directories found matching 'dist' 18:23:07 INFO - no previously-included directories found matching '.git*' 18:23:07 INFO - Installing collected packages: jsonschema, functools32 18:23:07 INFO - Running setup.py install for functools32 18:23:07 INFO - warning: no files found matching '*.txt' 18:23:07 INFO - no previously-included directories found matching 'build' 18:23:07 INFO - no previously-included directories found matching 'dist' 18:23:07 INFO - no previously-included directories found matching '.git*' 18:23:07 INFO - Successfully installed jsonschema functools32 18:23:07 INFO - Cleaning up... 18:23:07 INFO - Return code: 0 18:23:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:23:07 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')]} 18:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:07 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')]} 18:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:07 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 0x7fb323f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c038a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cce770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', '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 18:23:07 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 18:23:07 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 18:23:07 INFO - Using env: {'DISPLAY': ':0', 18:23:07 INFO - 'HOME': '/home/cltbld', 18:23:07 INFO - 'LANG': 'en_US.UTF-8', 18:23:07 INFO - 'LANGUAGE': 'en_US:en', 18:23:07 INFO - 'LOGNAME': 'cltbld', 18:23:07 INFO - 'MAIL': '/var/mail/cltbld', 18:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:07 INFO - 'MOZ_NO_REMOTE': '1', 18:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:07 INFO - 'NO_EM_RESTART': '1', 18:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:07 INFO - 'PWD': '/builds/slave/test', 18:23:07 INFO - 'SHELL': '/bin/bash', 18:23:07 INFO - 'SHLVL': '1', 18:23:07 INFO - 'TERM': 'linux', 18:23:07 INFO - 'TMOUT': '86400', 18:23:07 INFO - 'USER': 'cltbld', 18:23:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:07 INFO - '_': '/tools/buildbot/bin/python'} 18:23:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:07 INFO - Downloading/unpacking flake8 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:07 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 18:23:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:23:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:09 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 18:23:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:23:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23: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 18:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:23:10 INFO - no previously-included directories found matching 'docs/_build' 18:23:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:23:10 INFO - Running setup.py install for pep8 18:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:23:10 INFO - no previously-included directories found matching 'docs/_build' 18:23:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:23:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:23:10 INFO - Cleaning up... 18:23:10 INFO - Return code: 0 18:23:10 INFO - Running post-action listener: _resource_record_post_action 18:23:10 INFO - Running post-action listener: _start_resource_monitoring 18:23:10 INFO - Starting resource monitoring. 18:23:10 INFO - ##### 18:23:10 INFO - ##### Finished create-virtualenv step (success) 18:23:10 INFO - ##### 18:23:10 INFO - ##### 18:23:10 INFO - ##### Running install step. 18:23:10 INFO - ##### 18:23:10 INFO - Running pre-action listener: _resource_record_pre_action 18:23:10 INFO - Running main action method: install 18:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:23:11 INFO - Reading from file tmpfile_stdout 18:23:11 INFO - Detecting whether we're running mozinstall >=1.0... 18:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:23:11 INFO - Reading from file tmpfile_stdout 18:23:11 INFO - Output received: 18:23:11 INFO - Usage: mozinstall [options] installer 18:23:11 INFO - Options: 18:23:11 INFO - -h, --help show this help message and exit 18:23:11 INFO - -d DEST, --destination=DEST 18:23:11 INFO - Directory to install application into. [default: 18:23:11 INFO - "/builds/slave/test"] 18:23:11 INFO - --app=APP Application being installed. [default: firefox] 18:23:11 INFO - mkdir: /builds/slave/test/build/application 18:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:23:27 INFO - Reading from file tmpfile_stdout 18:23:27 INFO - Output received: 18:23:27 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:27 INFO - Running post-action listener: _resource_record_post_action 18:23:27 INFO - ##### 18:23:27 INFO - ##### Finished install step (success) 18:23:27 INFO - ##### 18:23:27 INFO - ##### 18:23:27 INFO - ##### Running run-tests step. 18:23:27 INFO - ##### 18:23:27 INFO - Running pre-action listener: _resource_record_pre_action 18:23:27 INFO - Running main action method: run_tests 18:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:27 INFO - Python 2.7.3 18:23:27 INFO - Return code: 0 18:23:27 INFO - grabbing minidump binary from tooltool 18:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d5d9c0>, '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 0x2d5e8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d5ed50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:23:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:23:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:23:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:27 INFO - Return code: 0 18:23:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:23:28 INFO - Return code: 0 18:23:28 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/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:23:28 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/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 18:23:28 INFO - Using env: {'DISPLAY': ':0', 18:23:28 INFO - 'HOME': '/home/cltbld', 18:23:28 INFO - 'LANG': 'en_US.UTF-8', 18:23:28 INFO - 'LANGUAGE': 'en_US:en', 18:23:28 INFO - 'LOGNAME': 'cltbld', 18:23:28 INFO - 'MAIL': '/var/mail/cltbld', 18:23:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:28 INFO - 'MOZ_NO_REMOTE': '1', 18:23:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:28 INFO - 'NO_EM_RESTART': '1', 18:23:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:28 INFO - 'PWD': '/builds/slave/test', 18:23:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:28 INFO - 'SHELL': '/bin/bash', 18:23:28 INFO - 'SHLVL': '1', 18:23:28 INFO - 'TERM': 'linux', 18:23:28 INFO - 'TMOUT': '86400', 18:23:28 INFO - 'USER': 'cltbld', 18:23:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192', 18:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:28 INFO - '_': '/tools/buildbot/bin/python'} 18:23:28 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/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 18:23:28 INFO - mozversion application_buildid: 20160529173637 18:23:28 INFO - mozversion application_changeset: 1cafe268e0149021d977aca6bf5af1478e52324d 18:23:28 INFO - mozversion application_display_name: Nightly 18:23:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:28 INFO - mozversion application_name: Firefox 18:23:28 INFO - mozversion application_remotingname: firefox 18:23:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:23:28 INFO - mozversion application_vendor: Mozilla 18:23:28 INFO - mozversion application_version: 49.0a1 18:23:28 INFO - mozversion platform_buildid: 20160529173637 18:23:28 INFO - mozversion platform_changeset: 1cafe268e0149021d977aca6bf5af1478e52324d 18:23:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:23:28 INFO - mozversion platform_version: 49.0a1 18:23:28 INFO - using testdate: 1464571408 18:23:28 INFO - actual date: 1464571408 18:23:28 INFO - starting webserver on 'localhost:37689' 18:23:28 INFO - SUITE-START | Running 2 tests 18:23:28 INFO - TEST-START | dromaeo_css 18:23:28 INFO - operating with platform_type : linux_ 18:23:28 INFO - Initialising browser for dromaeo_css test... 18:23:28 INFO - TEST-INFO | started process 5443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgPd5D4/profile http://localhost:37689/getInfo.html) 18:23:32 INFO - PROCESS | 5443 | __metrics Screen width/height:1600/1200 18:23:32 INFO - PROCESS | 5443 | colorDepth:24 18:23:32 INFO - PROCESS | 5443 | Browser inner width/height: 1024/697 18:23:32 INFO - PROCESS | 5443 | __metrics 18:23:33 ERROR - PROCESS | 5443 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:23:34 INFO - TEST-INFO | 5443: exit 0 18:23:34 INFO - Browser initialized. 18:23:34 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:34 INFO - TEST-INFO | started process 5525 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgPd5D4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:46 INFO - PROCESS | 5525 | RSS: Main: 156987392 18:23:46 INFO - PROCESS | 5525 | 18:24:37 INFO - PROCESS | 5525 | 1464571477629 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] 18:26:49 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37689/tests/dromaeo/cssquery-ext.html) 18:26:50 INFO - PROCESS | 5525 | RSS: Main: 239525888 18:26:50 INFO - PROCESS | 5525 | 18:29:53 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-ext.html (next: http://localhost:37689/tests/dromaeo/cssquery-jquery.html) 18:29:53 INFO - PROCESS | 5525 | RSS: Main: 242413568 18:29:53 INFO - PROCESS | 5525 | 18:32:55 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37689/tests/dromaeo/cssquery-mootools.html) 18:32:56 INFO - PROCESS | 5525 | RSS: Main: 247279616 18:32:56 INFO - PROCESS | 5525 | 18:35:58 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37689/tests/dromaeo/cssquery-prototype.html) 18:35:59 INFO - PROCESS | 5525 | RSS: Main: 237379584 18:35:59 INFO - PROCESS | 5525 | 18:39:02 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37689/tests/dromaeo/cssquery-yui.html) 18:39:02 INFO - PROCESS | 5525 | RSS: Main: 219807744 18:39:02 INFO - PROCESS | 5525 | 18:42:05 INFO - PROCESS | 5525 | Cycle 1(1): loaded http://localhost:37689/tests/dromaeo/cssquery-yui.html (next: http://localhost:37689/tests/dromaeo/cssquery-dojo.html) 18:42:06 INFO - PROCESS | 5525 | RSS: Main: 276275200 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | __start_tp_report 18:42:06 INFO - PROCESS | 5525 | _x_x_mozilla_page_load 18:42:06 INFO - PROCESS | 5525 | _x_x_mozilla_page_load_details 18:42:06 INFO - PROCESS | 5525 | |i|pagename|runs| 18:42:06 INFO - PROCESS | 5525 | |0;dojo.html;2669.6929853066895;2676.518226719192;2700.675918889733;2709.972900270997;2723.278331242221;4642.094791515656;4709.529047095289;4711.76441177941;4795.3526273953;4846.151923413402;4718.150732868083;4751.667383283171;4765.666403351758;4772.308015337774;4773.260144677572;4161.916761664765;4319.71921825082;4412.624926881219;4435.534268901767;4511.368408422823;4409.4708634963845;4451.532589078149;4467.10657868426;4472.284434490482;4504.909901801962;4253.787310634456;4284.871453856389;4316.719413238129;4323.092150648367;4364.694471387004;4308.138372325548;4327.415798867144;4369.235383807821;4391.29739241723;4396.868093957187;4380.364847097153;4381.605655491021;4383.32058530925;4392.736435813861;4408.7354758714255;4548.56788605082;4625.051864367797;4696.765161741933;4698.459677137104;4766.76166191689;5150.922736158961;5156.845294641167;5170.715610641413;5173.146430838907;5174.068667639824;5032.547070763612;5162.664426812283;5192.792288308463;5196.428392876781;5199.14214154664;4735.644826637981;4770.952290477086;4779.832705855279;4790.664653474223;4825.276208568743;5304.7347632618985;5319.946800531945;5323.653962492425;5325.174597937326;5326.547243484347;4914.115459217303;5087.465816089331;5108.744562771846;5109.642325037212;5110.182370820651;5050.0657378384385;5071.974640126849;5106.540411362995;5129.717865517432;5135.2553879687985;5092.567131793839;5097.719625420637;5100.056489549445;5104.515071068243;5152.149895267239;4896.583790377857;4973.726445045557;5077.543021128116;5082.33929589151;5091.516305950929;4360.65114790816;4368.060866913629;4385.890352741207;4428.31361139024;4431.578999994996;3805.771653700787;3814.6757525610633;3821.3694740368082;3834.7890866002635;3852.4221366794645;4292.7424354538825;4356.324769660708;4443.111377724468;4444.733316000986;4476.4180656514445;5100.38795344561;5109.565686916654;5142.331496905454;5143.27994080829;5197.29836472079;4411.536788637147;4414.668899832525;4415.757133357632;4465.129299786576;4487.6634252431195;4504.662150338672;4571.954280457219;4615.884602884955;4632.235681112578;4635.884102897455;4831.73425461603;4843.927341089816;4850.054239423349;4883.926741098886;4897.5592196701045;5129.7691603877165;5140.717260550706;5142.742862856917;5144.459831717616;5147.176451767699;4696.178168820375;4748.762561871962;4798.0403919217;4802.183628783046;4866.707997520019;5011.4487406386;5020.221865610836;5024.020316038337;5028.823991160292;5068.467810879804;5103.668261562986;5113.923291150561;5114.053900028507;5118.769655365592;5130.51260130287;4851.490593487633;4881.121398148225;4890.266460030882;4907.312976183266;4968.22992436173;4927.802887884444;4957.677750946177;4958;4960.7767650456535;5018.698878067327;4760.523947605212;4884.853454396374;4904.485029071896;4908.803647854189;4961.156603377362;4934.605231581537;4986.775595098156;4995.900081998267;4997.675151115166;5021.673591216705;4890.6332025097545;4895;4898.2652602109965;4927.384076990376;4950.133726597903 18:42:06 INFO - PROCESS | 5525 | |1;ext.html;595.0598055072983;599.4305409860633;601.332520901799;604.7732100462326;612.3570251236201;27636.723632763667;30444.54333185001;39788;41868.371974420406;41869;42807.571924280746;43502.56497435024;43517.34723979143;43519.129617407634;43642.56357436425;7799.92200077999;7925.643346049442;7971.163027882076;7971.441999060068;7973.880391794128;7857.960710196469;7954.403419743513;7998.200179982014;8002.319802816767;8004.479708818937;8016.63925123368;8070.515769053846;8072.475289106219;8073.31376832967;8076.9192308077045;7939.36485081195;7966.362690984736;7991.760247192594;7992.200779922019;7994.160613135653;7853.489523180975;7944.2850143486785;7944.602769861542;7948.165442628557;7948.483348582381;38702.032449188715;39219.80390098031;39227.60772392268;39235;39241.60758392408;3097.4734295169874;3195.2491164576304;3217.581714377139;3471.687548120656;3772.7547709399078;29438.411231775244;29976;30065.699343006727;30080;31051.534226986612;3445.8277086145845;3464.72282217422;3481.9651803482147;3488.5464889564446;3551.875684351061;246111;246850;247105;247959;248297;44961.55038449639;53260.201096983576;53672.73163634157;53701.731491342296;53963.460365396626;54995.72502137544;56878.43121568731;56915.430845691844;61175.69412152911;61550.38449615536;12986.285754283415;13165.736685266242;13213.339333033502;13226.140300880412;13232.801507977389;9131.223845973034;9501.23990080792;9616.134547890724;9690.70927872165;9692.369995950381;15639.296231669603;15965.760513592306;16006.439774607832;16207.351705931867;16355.427560035341;5670.193254339046;5726.513246374022;5736.77052917887;5789.131630255495;5876.471117599351;793.9563324017118;813.7436707437139;957.3681370295571;958.9089036541517;1021.8160731068479;28353.858230709127;28859.422811543653;28866.855665721538;28925.276868078465;29033.85483072571;879.5338470610586;918.2516249256835;918.5269586163131;951.5908159491485;981.842905135175;14657.853421465863;15000.249987500581;15220.238988050774;15305.158216297988;15351.923240383727;361545;590788;605267;606210;606627;51543.226851597254;51605.22592161121;52166.21750673743;52190.47809521932;52218.738906305225;1070.2454769387718;1146.9082473402275;1213.2538488829307;1230.7846126927752;1249.7125661097998;25405;29009.274768130967;29155.125346239645;29452.410951781272;29485;42211.3668294982;42581;42618.360724588536;42695.57304426916;42765.78617106895;295201;295666;296196;296289;296618;22343.77656223417;22900.31299061031;23143.074277029405;23143.652845206998;24389.634155487325;43426.348604771585;43445;44053.33919991267;44205.77897110623;44579.55420445754;36724.81637591902;37182.83945864632;37889.2422151561;38080;38438.807805960794;36294.63705362913;36824.26351473009;36885.63114368822;37027.444588330654;37072;33940;34240.657593424745;34501.82749086339;36570.26859462849;36986.8150659245;32231.194220144684;32656.183595410304;33058.83470582632;33285;33443.49834752432;25226.243212703648;25320.366990825376;26129.346766330993;27422.177334679993;27608.861955690092 18:42:06 INFO - PROCESS | 5525 | |2;jquery.html;374.75266324226016;379.624172069651;380.9200067985723;394.30995757424506;395.3575439910146;16235;16664.083475408846;16952.237149328295;17019.659606807858;17086;16329.83670163298;16490;17172.227249773758;17224.741628875578;17239.91380043101;4743.620510359172;4862.902741945177;4895.730734809584;4903.289023091649;4912.410510738699;3561.3233485637775;3712.51737274154;3991.341428664267;5167.7932882684645;6177.3822617738315;6011.639301641893;6141;6176.938230617704;6214.627122372672;6230.813075607739;5487.2317875497465;5684;5691.573132015115;5859.648421094748;6125.846853828668;3463.0995941055508;5097.643164978453;5442.428545002757;5452.972735136299;5456.727163641842;12401.25592464455;12423.3788310585;12454.81317780234;12534.310612916286;17644.735328970077;1758.7273972534279;1768.938087166956;1864.114545590847;2151.429871084148;2309.226409152919;8973.955130224373;9075.36472446929;9332.813343733087;9351.76620584484;9546.713598592054;1655.4371513685405;1661.4849396687066;1688.4512533426685;1693.2126561149125;1735.9305627774875;64477.677611611645;64982.025269620994;66555.66722166407;66747.3325266746;67560;9652.90347096534;9850.458224797561;9879;9923.751906202402;9980.201583873273;13180.011499137603;13214.273214973076;13248.403821827847;13257.204567725967;13275.535356262484;6794;6912.205096413876;6919.550229235084;7004.684789184499;7022.332878376546;6410.711517981702;6490.935090649127;6561.573497722633;6565.474762019027;6592.604443733296;4200.579942005775;4214.704970652025;4242.427272318259;4350.173467041315;4423.1153769246275;1673.305578185064;1685.1237356574513;1698.4395149600603;1744.8691348148939;1763.7530745695612;855.1448551448551;893.3657103456619;906.4108329585822;933.4446004627235;956.2350119904049;15717.371305147895;16673.49979500617;17168.22742976569;17338.219780109925;17450.12749362552;828.7928017995501;857.3098655582316;890.7149712092069;902.7923577577062;915.1397686174975;9302.53487325631;9670.854937175807;9830.803383932423;9873.111419972094;9875.209983201326;381914;382270;383133;392078;394654;57661;73314.26685733075;76251;78470.6076469614;80718;831.5179190117494;902.702108304258;908.6955869783696;937.5265490927038;939.0281059103795;13357.732845343233;14968;15026.098434093989;15749.842501574838;16136.205767856782;29562;30232.395352093274;34911.476327854594;35077;35457.64542354647;44443.555564443945;46318.30522542232;46452;46709.532904671876;47230.291545626154;7690.307872291521;7695.730649427467;7776.183500732342;7808.804779880548;7929.603519823871;8332.916670833214;10297.74255643615;11401.828972565252;11479.598214062467;11485.770284594428;9116.954415227881;9472.668456603986;9671.516424178622;9675.612975480903;9909.950450247703;9616.807663846825;9848.113669769755;9909.108180263811;10025.097741203326;10238.846417303597;9406.717798466058;9793.363431376938;9916.256280778825;10013.19894408431;10177.542010609688;7545.471816972759;7577.1665116836;7606.885896711443;7668.348190403878;7734.419918506022;7672.194419585863;8792.956035220039;9998.750031249278;10003.699889003341;10033.996600340199 18:42:06 INFO - PROCESS | 5525 | |3;mootools.html;1220.5789002794045;1402.3479082226766;1442.956711298661;1560.2588770334085;1764.488298393466;6841.965790171054;7451.478396512246;7679.769606911795;7763.417743669219;7943;7553.509021913572;7683.654235559399;7690.346320562745;7703.191164927686;7703.807404814883;2151.623465893474;2176.7170267865235;2177.2053200581754;2178.5098352870637;2181.7381914170323;2118.7139736135655;2142.6893100500497;2178.237616834111;2180.6401943679275;2181.4437318483842;2149.312220089572;2172.913083476659;2178.2267295110246;2179.498715295483;2182.061713463218;2106.483911441699;2108.630989576833;2110.293051827632;2110.6728457089175;2114.0381126587367;2057.8971051447515;2123.6495978163584;2132.4882028313095;2139.0801955159277;2141;7657.502262352929;7744.574048427335;7783.299503044753;7791.727289544839;7802.843943121163;4470.128324976631;4566.360709500673;4621.329907163443;4625.976870115628;4627.259638457831;2955.0543825975697;2956.52695568708;3034.7723920706035;3037.407705497429;3040.3919216156637;961.5721004153155;981.5582987655583;993.8012397520525;998.825205589019;1004.3873237325225;10554.525046372932;10787.137029037658;10819.567217311298;10821.83767243492;10858.022777950022;4248.320268756984;4393.868183954486;4482.238019536687;4482.820687172477;4531.504603480844;8372.49765014089;8382.832343353099;8412.831743365221;8423.915760842436;8427.452215605967;4483.349914262414;4485.910281794346;4488.236999710057;4490.146872094291;4495.617872480811;3592.604813470516;3808.5429748430365;4148.398482220061;4163.354680024601;4166.166766646683;3425.1950791563963;3489.633588473174;3551.6093229744706;3577.856885724542;3683.9447408288897;1019.9898001020043;1025.8102251083574;1028.7654814222813;1036.1762398892897;1037.3620223562605;863.8229163021565;869.1742844297942;869.4652788535005;916.0701887584107;929.990700093004;5149.407818100892;5310.548603368679;5433.157860531624;5434.076207044812;5472.2338872557875;418.13446166435796;419.2662840029948;428.18644575307167;428.3189728331905;439.35195586509644;3620.2397496526023;3652.945205821915;3672.4307732301536;3676.1361080146144;3723.3484140275345;9526.237900967906;9584.616615335308;9845.852312215322;9867.309288349747;9985.350952188084;9861.852072218922;9870;9872.851907221397;9881.851772223423;9905.20758339331;6982.1272340957385;7267;7419.517731347445;7530.021097257431;7534.585597792065;5643.71781410936;5653.293338332708;5675.177099320658;5752.856178595569;5884.029135192816;8897.199252067345;8916.242119419921;9097;9100.59047342858;9112.362134650775;8940.418872773249;9124.726258212264;9138.497382644018;9145.085491450802;9203.953980230055;6291.874162516816;6511.609303441809;6522.15212022434;6659.900101498384;6660.2673705893285;7175.928240717526;7373.483856130019;7382.9630851845395;7430.888536672062;7444;6695.631740254333;6794.218664853407;6827.351401616838;6936.861262774817;6965.930340696528;6661.566998145105;6692.230393504809;6716.126903502513;6758.831029224309;6807.0810440589885;6125.724342404692;6188.81433556994;6299.905501417574;6534.901976470452;6538.1500404947055;4460.821567137278;4552.704074235164;4612.031473390625;4653.627709783277;4840.056189043103;4570.651657761035;4627.884302892455;4668.836590719308;4727.432708074915;4779.5220477951925 18:42:06 INFO - PROCESS | 5525 | |4;prototype.html;311.18935173872063;312.518721169399;315.21354221218064;315.9668234835342;321.3492677328411;4792.6165906727465;4818.494058123894;4838.733869637169;4845.958119004414;4885.487023862488;4771.351899695667;4789.856304310868;4816.855494335166;4817.494163112875;4827.131116399047;3588.8743893963715;3612.837422316002;3700.760245317822;3731.496248006525;3774.2828862516035;3709.9072523187;3713.2573485303046;3716.9628303716886;3738.6448287412654;3757.549094108711;3550.289942011608;3661.7986010769396;3695.778253304797;3717.405215165574;3729.7762134271893;3616.8914932552066;3722.981385093071;3753.868614598476;3758.9436158457647;3771.69826413889;3343.3814744272386;3351;3360.6639336066196;3364.9831750841336;3385.8984230473125;4506.707064040859;4535.297028960516;4576.290674945356;4595.333676616872;4596.747178905159;4098.938515922264;4236.394617310363;4248.638865696419;4273.166732487166;4349.608535231812;4946.802127914879;4950.975245123751;4988.87527811804;4992.229312731432;5003.498950314891;3670.5595328560476;3670.926581468356;3681.7054635629083;3718.1076541630086;3723.1622884850963;4957.454679985199;5156.685045313458;5170.715610641413;5183.170692689189;5203.271541984087;3689.6679298863232;3690.3357395669036;3702.4816525686424;3706.4440333949583;3724.1434470071495;3762.2851658185405;3775.339315619756;3776.5090538229856;3776.6962153086597;3781.829817658157;4277.123189746095;4277.914441711148;4293.97853010733;4300.2904520754355;4321.58944900234;4781.42622885256;4914.36113305268;4949.7030178189425;4969.27945447908;5063.721495317719;1301.7006088599676;1309.5154792726562;1325.8806707396188;1327.7875539913762;1331.5672406467936;856.4390324337569;864.8400045991511;886.2555453419159;902.3999040637902;902.5487256371814;4361.323994780814;4367.497737760134;4390.4511936008;4403.735775853395;4416.845410410684;5077.720725360066;5079.593632509391;5099.69401835891;5105.795768169231;5166;4253.829846806155;4373.147236288957;4382.430284063115;4390.651001714132;4429.75636339998;2698.865056747155;2711.3628297350106;2722.945541089167;2722.9727702723117;2724.768394686474;4807.591354734816;4811.326414302001;4817.2774083887;4825;4840.322354870321;5034.974825125851;5140.460251673595;5152.9742351288005;5196.220566914993;5208.921866172011;4761.023990081955;4770.594499467584;4791.52084791532;4795.592374648194;4809.78355973982;5015.322931404214;5016.297718319365;5051.974740126276;5054.079449809049;5059.797608095635;4733.3373327734835;4924;4976.129177393869;4976.651634385558;4989.151844186569;4771.5467030632035;4794.808207671654;4802.687825291345;4828.855134345975;4840.1529732296;3666.431703086026;3685.212671854202;3694.9076273093387;3702.4076147817295;3707.4624179494363;4926.458089610069;5036.294918711456;5046.4701206374275;5134.974325128351;5241.790328386822;4949.82675606352;4971.676841005323;5015;5038.370203724535;5048.242763585492;4988.5260900214425;5004.574611158092;5019;5036.748162591929;5046.369203849519;4848.515148485264;4938.975305123451;4970.130227210151;4973.179425394769;4992.975035124801;4790.592799611933;4879.4876537963;5013.197888337849;5037.823676171316;5044.394672639307;4907.656464047483;4912.975435122801;4939.185034469272;4977.228529577921;4978.128827455109 18:42:06 INFO - PROCESS | 5525 | |5;yui.html;581.4242409183521;671.8757029949459;683.9897401538975;686.3548264631246;716.9964150179248;12402.379881005947;13163.07858449909;13168.209907405539;13208.7358252835;13222.338883055856;12302.077344199199;12344.814827777567;12351.444185011673;12352.382380880965;12353.876461235384;613.619555875358;761.3946912204797;776.216021817965;791.4143533784986;792.3938187286731;794.6543253684665;797.9281864632181;798.4410912361341;798.9760307190764;800.9599520024004;837.4389159263308;841.6296829395077;846.547097302943;848.101012926297;848.9278411335044;645.983850403739;809.3323008517938;830.5016989806068;839.2866063845744;839.5844057191699;610.633619828104;611.2023808929346;611.2542697908544;611.4191518057819;611.8837420890017;12111;12202.328871912041;12211.938940305241;12233.20484168535;12256;11896.940515297454;12189;12234.204776689576;12308.81536776949;12317.815232771516;11236.775264494665;11244.212905096647;11263.887361126448;11287.757554003463;11299.096072314194;4021.7184797063924;4055.8174882130374;4069.694772892045;4109.91780164398;4178.185253875496;44314.77842610766;44716;44996.55003449989;45083.323750143776;45109.323360149625;9644.421334719938;9804.656837010813;9896.10935015852;9949.950250248703;10041.246906482043;16768.245428955724;16813.07528085967;16945.15274236308;16948.661026779642;16988.49034528966;6359.491240700733;6579.374959378852;6624.304448032984;6680.665966701646;6693.263740988487;3673.9265214696093;3685.0971511979737;3709.2396058808417;3721.5906250312446;3851.5955824638568;2766.3775650478483;2870.9138725838257;2871.612332335108;2872.3250036241466;2872.3967966727223;520.5627273090622;560.5431573267855;585.2274997003999;594.2007999241014;604.1903848842484;478.2443738892611;527.2328761651787;590.3624085987124;616.2204810914197;633.4330773957282;6792.490563207779;7647.120581133129;7687.154413014676;7750.341220996165;7799.844003119906;503.5493233555947;550.1939658400382;601.5442628838134;610.9755609775648;616.2235583165244;3536.681698647134;3671.853125874935;3721.5534135903863;3727.7763334200035;3749.3251214781603;55551.72224138853;56435;56612.43387566072;56882.43117568854;56889.43110568924;49053.75473122683;49563.50436495661;49831;50733.74633126811;51287.230691539655;45287.77356113264;46349.536504634525;46721;47020.764896175984;47101;7055.18865330473;7243.420526357773;7419.183889772229;7454.515456495311;7547.924520754942;27477;28233.43533129367;28871.56692649654;31222.531662024634;31654;38658.22683546257;39376;39800;40325.79837100796;40361.79819100886;10326.948365258126;10614.257002009785;10677;10972.396518191576;11205.60780372683;11839.230450020721;12167.817482737943;12345.259284442962;12842.293673848028;12862.163959342613;10724.624638137628;10914.454277286262;11120.61077862271;11300.43497825089;11367.431628418712;10584.523696433525;10743.516541755484;10772.138228941509;10774.892251077388;11363.090952723596;10884.129269658566;10910;11005;11005.889941100486;11315.151363647925;9057.902725009113;9139.177474027369;9184.908150918405;9487.478188699688;9534.904650953678;8921.15249051339;9007.729768106967;9095.317851161057;9217.539123043687;9233.158919263356 18:42:06 INFO - PROCESS | 5525 | __end_tp_report 18:42:06 INFO - PROCESS | 5525 | __start_cc_report 18:42:06 INFO - PROCESS | 5525 | _x_x_mozilla_cycle_collect,955 18:42:06 INFO - PROCESS | 5525 | __end_cc_report 18:42:06 INFO - PROCESS | 5525 | __startTimestamp1464572526064__endTimestamp 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | ------- Summary: start ------- 18:42:06 INFO - PROCESS | 5525 | Number of tests: 6 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#0] dojo.html Cycles:165 Average:4722.41 Median:4850.05 stddev:493.56 (10.2%) stddev-sans-first:468.07 18:42:06 INFO - PROCESS | 5525 | Values: 2669.7 2676.5 2700.7 2710.0 2723.3 4642.1 4709.5 4711.8 4795.4 4846.2 4718.2 4751.7 4765.7 4772.3 4773.3 4161.9 4319.7 4412.6 4435.5 4511.4 4409.5 4451.5 4467.1 4472.3 4504.9 4253.8 4284.9 4316.7 4323.1 4364.7 4308.1 4327.4 4369.2 4391.3 4396.9 4380.4 4381.6 4383.3 4392.7 4408.7 4548.6 4625.1 4696.8 4698.5 4766.8 5150.9 5156.8 5170.7 5173.1 5174.1 5032.5 5162.7 5192.8 5196.4 5199.1 4735.6 4771.0 4779.8 4790.7 4825.3 5304.7 5319.9 5323.7 5325.2 5326.5 4914.1 5087.5 5108.7 5109.6 5110.2 5050.1 5072.0 5106.5 5129.7 5135.3 5092.6 5097.7 5100.1 5104.5 5152.1 4896.6 4973.7 5077.5 5082.3 5091.5 4360.7 4368.1 4385.9 4428.3 4431.6 3805.8 3814.7 3821.4 3834.8 3852.4 4292.7 4356.3 4443.1 4444.7 4476.4 5100.4 5109.6 5142.3 5143.3 5197.3 4411.5 4414.7 4415.8 4465.1 4487.7 4504.7 4572.0 4615.9 4632.2 4635.9 4831.7 4843.9 4850.1 4883.9 4897.6 5129.8 5140.7 5142.7 5144.5 5147.2 4696.2 4748.8 4798.0 4802.2 4866.7 5011.4 5020.2 5024.0 5028.8 5068.5 5103.7 5113.9 5114.1 5118.8 5130.5 4851.5 4881.1 4890.3 4907.3 4968.2 4927.8 4957.7 4958.0 4960.8 5018.7 4760.5 4884.9 4904.5 4908.8 4961.2 4934.6 4986.8 4995.9 4997.7 5021.7 4890.6 4895.0 4898.3 4927.4 4950.1 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#1] ext.html Cycles:180 Average:51511.20 Median:39223.71 stddev:105291.31 (268.4%) stddev-sans-first:105517.28 18:42:06 INFO - PROCESS | 5525 | Values: 595.1 599.4 601.3 604.8 612.4 27636.7 30444.5 39788.0 41868.4 41869.0 42807.6 43502.6 43517.3 43519.1 43642.6 7799.9 7925.6 7971.2 7971.4 7973.9 7858.0 7954.4 7998.2 8002.3 8004.5 8016.6 8070.5 8072.5 8073.3 8076.9 7939.4 7966.4 7991.8 7992.2 7994.2 7853.5 7944.3 7944.6 7948.2 7948.5 38702.0 39219.8 39227.6 39235.0 39241.6 3097.5 3195.2 3217.6 3471.7 3772.8 29438.4 29976.0 30065.7 30080.0 31051.5 3445.8 3464.7 3482.0 3488.5 3551.9 246111.0 246850.0 247105.0 247959.0 248297.0 44961.6 53260.2 53672.7 53701.7 53963.5 54995.7 56878.4 56915.4 61175.7 61550.4 12986.3 13165.7 13213.3 13226.1 13232.8 9131.2 9501.2 9616.1 9690.7 9692.4 15639.3 15965.8 16006.4 16207.4 16355.4 5670.2 5726.5 5736.8 5789.1 5876.5 794.0 813.7 957.4 958.9 1021.8 28353.9 28859.4 28866.9 28925.3 29033.9 879.5 918.3 918.5 951.6 981.8 14657.9 15000.2 15220.2 15305.2 15351.9 361545.0 590788.0 605267.0 606210.0 606627.0 51543.2 51605.2 52166.2 52190.5 52218.7 1070.2 1146.9 1213.3 1230.8 1249.7 25405.0 29009.3 29155.1 29452.4 29485.0 42211.4 42581.0 42618.4 42695.6 42765.8 295201.0 295666.0 296196.0 296289.0 296618.0 22343.8 22900.3 23143.1 23143.7 24389.6 43426.3 43445.0 44053.3 44205.8 44579.6 36724.8 37182.8 37889.2 38080.0 38438.8 36294.6 36824.3 36885.6 37027.4 37072.0 33940.0 34240.7 34501.8 36570.3 36986.8 32231.2 32656.2 33058.8 33285.0 33443.5 25226.2 25320.4 26129.3 27422.2 27608.9 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#2] jquery.html Cycles:180 Average:23437.02 Median:5305.11 stddev:63752.78 (1201.7%) stddev-sans-first:63908.11 18:42:06 INFO - PROCESS | 5525 | Values: 374.8 379.6 380.9 394.3 395.4 16235.0 16664.1 16952.2 17019.7 17086.0 16329.8 16490.0 17172.2 17224.7 17239.9 4743.6 4862.9 4895.7 4903.3 4912.4 3561.3 3712.5 3991.3 5167.8 6177.4 6011.6 6141.0 6176.9 6214.6 6230.8 5487.2 5684.0 5691.6 5859.6 6125.8 3463.1 5097.6 5442.4 5453.0 5456.7 12401.3 12423.4 12454.8 12534.3 17644.7 1758.7 1768.9 1864.1 2151.4 2309.2 8974.0 9075.4 9332.8 9351.8 9546.7 1655.4 1661.5 1688.5 1693.2 1735.9 64477.7 64982.0 66555.7 66747.3 67560.0 9652.9 9850.5 9879.0 9923.8 9980.2 13180.0 13214.3 13248.4 13257.2 13275.5 6794.0 6912.2 6919.6 7004.7 7022.3 6410.7 6490.9 6561.6 6565.5 6592.6 4200.6 4214.7 4242.4 4350.2 4423.1 1673.3 1685.1 1698.4 1744.9 1763.8 855.1 893.4 906.4 933.4 956.2 15717.4 16673.5 17168.2 17338.2 17450.1 828.8 857.3 890.7 902.8 915.1 9302.5 9670.9 9830.8 9873.1 9875.2 381914.0 382270.0 383133.0 392078.0 394654.0 57661.0 73314.3 76251.0 78470.6 80718.0 831.5 902.7 908.7 937.5 939.0 13357.7 14968.0 15026.1 15749.8 16136.2 29562.0 30232.4 34911.5 35077.0 35457.6 44443.6 46318.3 46452.0 46709.5 47230.3 7690.3 7695.7 7776.2 7808.8 7929.6 8332.9 10297.7 11401.8 11479.6 11485.8 9117.0 9472.7 9671.5 9675.6 9910.0 9616.8 9848.1 9909.1 10025.1 10238.8 9406.7 9793.4 9916.3 10013.2 10177.5 7545.5 7577.2 7606.9 7668.3 7734.4 7672.2 8793.0 9998.8 10003.7 10034.0 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#3] mootools.html Cycles:180 Average:5129.82 Median:4994.73 stddev:2896.68 (58.0%) stddev-sans-first:2889.91 18:42:06 INFO - PROCESS | 5525 | Values: 1220.6 1402.3 1443.0 1560.3 1764.5 6842.0 7451.5 7679.8 7763.4 7943.0 7553.5 7683.7 7690.3 7703.2 7703.8 2151.6 2176.7 2177.2 2178.5 2181.7 2118.7 2142.7 2178.2 2180.6 2181.4 2149.3 2172.9 2178.2 2179.5 2182.1 2106.5 2108.6 2110.3 2110.7 2114.0 2057.9 2123.6 2132.5 2139.1 2141.0 7657.5 7744.6 7783.3 7791.7 7802.8 4470.1 4566.4 4621.3 4626.0 4627.3 2955.1 2956.5 3034.8 3037.4 3040.4 961.6 981.6 993.8 998.8 1004.4 10554.5 10787.1 10819.6 10821.8 10858.0 4248.3 4393.9 4482.2 4482.8 4531.5 8372.5 8382.8 8412.8 8423.9 8427.5 4483.3 4485.9 4488.2 4490.1 4495.6 3592.6 3808.5 4148.4 4163.4 4166.2 3425.2 3489.6 3551.6 3577.9 3683.9 1020.0 1025.8 1028.8 1036.2 1037.4 863.8 869.2 869.5 916.1 930.0 5149.4 5310.5 5433.2 5434.1 5472.2 418.1 419.3 428.2 428.3 439.4 3620.2 3652.9 3672.4 3676.1 3723.3 9526.2 9584.6 9845.9 9867.3 9985.4 9861.9 9870.0 9872.9 9881.9 9905.2 6982.1 7267.0 7419.5 7530.0 7534.6 5643.7 5653.3 5675.2 5752.9 5884.0 8897.2 8916.2 9097.0 9100.6 9112.4 8940.4 9124.7 9138.5 9145.1 9204.0 6291.9 6511.6 6522.2 6659.9 6660.3 7175.9 7373.5 7383.0 7430.9 7444.0 6695.6 6794.2 6827.4 6936.9 6965.9 6661.6 6692.2 6716.1 6758.8 6807.1 6125.7 6188.8 6299.9 6534.9 6538.2 4460.8 4552.7 4612.0 4653.6 4840.1 4570.7 4627.9 4668.8 4727.4 4779.5 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#4] prototype.html Cycles:180 Average:4149.07 Median:4785.64 stddev:1182.12 (24.7%) stddev-sans-first:1149.80 18:42:06 INFO - PROCESS | 5525 | Values: 311.2 312.5 315.2 316.0 321.3 4792.6 4818.5 4838.7 4846.0 4885.5 4771.4 4789.9 4816.9 4817.5 4827.1 3588.9 3612.8 3700.8 3731.5 3774.3 3709.9 3713.3 3717.0 3738.6 3757.5 3550.3 3661.8 3695.8 3717.4 3729.8 3616.9 3723.0 3753.9 3758.9 3771.7 3343.4 3351.0 3360.7 3365.0 3385.9 4506.7 4535.3 4576.3 4595.3 4596.7 4098.9 4236.4 4248.6 4273.2 4349.6 4946.8 4951.0 4988.9 4992.2 5003.5 3670.6 3670.9 3681.7 3718.1 3723.2 4957.5 5156.7 5170.7 5183.2 5203.3 3689.7 3690.3 3702.5 3706.4 3724.1 3762.3 3775.3 3776.5 3776.7 3781.8 4277.1 4277.9 4294.0 4300.3 4321.6 4781.4 4914.4 4949.7 4969.3 5063.7 1301.7 1309.5 1325.9 1327.8 1331.6 856.4 864.8 886.3 902.4 902.5 4361.3 4367.5 4390.5 4403.7 4416.8 5077.7 5079.6 5099.7 5105.8 5166.0 4253.8 4373.1 4382.4 4390.7 4429.8 2698.9 2711.4 2722.9 2723.0 2724.8 4807.6 4811.3 4817.3 4825.0 4840.3 5035.0 5140.5 5153.0 5196.2 5208.9 4761.0 4770.6 4791.5 4795.6 4809.8 5015.3 5016.3 5052.0 5054.1 5059.8 4733.3 4924.0 4976.1 4976.7 4989.2 4771.5 4794.8 4802.7 4828.9 4840.2 3666.4 3685.2 3694.9 3702.4 3707.5 4926.5 5036.3 5046.5 5135.0 5241.8 4949.8 4971.7 5015.0 5038.4 5048.2 4988.5 5004.6 5019.0 5036.7 5046.4 4848.5 4939.0 4970.1 4973.2 4993.0 4790.6 4879.5 5013.2 5037.8 5044.4 4907.7 4913.0 4939.2 4977.2 4978.1 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - PROCESS | 5525 | [#5] yui.html Cycles:180 Average:13094.31 Median:45198.55 stddev:15218.49 (33.7%) stddev-sans-first:15232.17 18:42:06 INFO - PROCESS | 5525 | Values: 581.4 671.9 684.0 686.4 717.0 12402.4 13163.1 13168.2 13208.7 13222.3 12302.1 12344.8 12351.4 12352.4 12353.9 613.6 761.4 776.2 791.4 792.4 794.7 797.9 798.4 799.0 801.0 837.4 841.6 846.5 848.1 848.9 646.0 809.3 830.5 839.3 839.6 610.6 611.2 611.3 611.4 611.9 12111.0 12202.3 12211.9 12233.2 12256.0 11896.9 12189.0 12234.2 12308.8 12317.8 11236.8 11244.2 11263.9 11287.8 11299.1 4021.7 4055.8 4069.7 4109.9 4178.2 44314.8 44716.0 44996.6 45083.3 45109.3 9644.4 9804.7 9896.1 9950.0 10041.2 16768.2 16813.1 16945.2 16948.7 16988.5 6359.5 6579.4 6624.3 6680.7 6693.3 3673.9 3685.1 3709.2 3721.6 3851.6 2766.4 2870.9 2871.6 2872.3 2872.4 520.6 560.5 585.2 594.2 604.2 478.2 527.2 590.4 616.2 633.4 6792.5 7647.1 7687.2 7750.3 7799.8 503.5 550.2 601.5 611.0 616.2 3536.7 3671.9 3721.6 3727.8 3749.3 55551.7 56435.0 56612.4 56882.4 56889.4 49053.8 49563.5 49831.0 50733.7 51287.2 45287.8 46349.5 46721.0 47020.8 47101.0 7055.2 7243.4 7419.2 7454.5 7547.9 27477.0 28233.4 28871.6 31222.5 31654.0 38658.2 39376.0 39800.0 40325.8 40361.8 10326.9 10614.3 10677.0 10972.4 11205.6 11839.2 12167.8 12345.3 12842.3 12862.2 10724.6 10914.5 11120.6 11300.4 11367.4 10584.5 10743.5 10772.1 10774.9 11363.1 10884.1 10910.0 11005.0 11005.9 11315.2 9057.9 9139.2 9184.9 9487.5 9534.9 8921.2 9007.7 9095.3 9217.5 9233.2 18:42:06 INFO - PROCESS | 5525 | -------- Summary: end -------- 18:42:06 INFO - PROCESS | 5525 | 18:42:06 INFO - TEST-INFO | 5525: exit 0 18:42:06 INFO - TEST-OK | dromaeo_css | took 1118633ms 18:42:06 INFO - TEST-START | kraken 18:42:06 INFO - operating with platform_type : linux_ 18:42:06 INFO - Initialising browser for kraken test... 18:42:06 INFO - TEST-INFO | started process 5700 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGeDBkA/profile http://localhost:37689/getInfo.html) 18:42:10 INFO - PROCESS | 5700 | __metrics Screen width/height:1600/1200 18:42:10 INFO - PROCESS | 5700 | colorDepth:24 18:42:10 INFO - PROCESS | 5700 | Browser inner width/height: 1024/697 18:42:10 INFO - PROCESS | 5700 | __metrics 18:42:11 ERROR - PROCESS | 5700 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:42:12 INFO - TEST-INFO | 5700: exit 0 18:42:12 INFO - Browser initialized. 18:42:12 INFO - Running cycle 1/1 for kraken test... 18:42:12 INFO - TEST-INFO | started process 5780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGeDBkA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:24 INFO - PROCESS | 5780 | RSS: Main: 157229056 18:42:24 INFO - PROCESS | 5780 | 18:42:27 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37689/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:28 INFO - PROCESS | 5780 | RSS: Main: 213946368 18:42:28 INFO - PROCESS | 5780 | 18:42:31 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37689/tests/kraken/driver.html?testName=audio-dft) 18:42:31 INFO - PROCESS | 5780 | RSS: Main: 202752000 18:42:31 INFO - PROCESS | 5780 | 18:42:35 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37689/tests/kraken/driver.html?testName=audio-fft) 18:42:35 INFO - PROCESS | 5780 | RSS: Main: 270192640 18:42:35 INFO - PROCESS | 5780 | 18:42:37 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37689/tests/kraken/driver.html?testName=audio-oscillator) 18:42:38 INFO - PROCESS | 5780 | RSS: Main: 180461568 18:42:38 INFO - PROCESS | 5780 | 18:42:40 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37689/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:41 INFO - PROCESS | 5780 | RSS: Main: 184958976 18:42:41 INFO - PROCESS | 5780 | 18:42:44 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37689/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:44 INFO - PROCESS | 5780 | RSS: Main: 220626944 18:42:44 INFO - PROCESS | 5780 | 18:42:48 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37689/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:48 INFO - PROCESS | 5780 | RSS: Main: 221237248 18:42:48 INFO - PROCESS | 5780 | 18:42:51 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37689/tests/kraken/driver.html?testName=json-parse-financial) 18:42:52 INFO - PROCESS | 5780 | RSS: Main: 221257728 18:42:52 INFO - PROCESS | 5780 | 18:42:54 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37689/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:54 INFO - PROCESS | 5780 | RSS: Main: 206323712 18:42:54 INFO - PROCESS | 5780 | 18:42:56 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:57 INFO - PROCESS | 5780 | RSS: Main: 212348928 18:42:57 INFO - PROCESS | 5780 | 18:42:59 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:43:00 INFO - PROCESS | 5780 | RSS: Main: 218345472 18:43:00 INFO - PROCESS | 5780 | 18:43:02 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:43:03 INFO - PROCESS | 5780 | RSS: Main: 223469568 18:43:03 INFO - PROCESS | 5780 | 18:43:06 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:43:06 INFO - PROCESS | 5780 | RSS: Main: 224010240 18:43:06 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | Cycle 1(1): loaded http://localhost:37689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37689/tests/kraken/driver.html?testName=ai-astar) 18:43:08 INFO - PROCESS | 5780 | RSS: Main: 266723328 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | __start_tp_report 18:43:08 INFO - PROCESS | 5780 | _x_x_mozilla_page_load 18:43:08 INFO - PROCESS | 5780 | _x_x_mozilla_page_load_details 18:43:08 INFO - PROCESS | 5780 | |i|pagename|runs| 18:43:08 INFO - PROCESS | 5780 | |0;ai-astar;116;111;114;113;114;113;112;115;115;115 18:43:08 INFO - PROCESS | 5780 | |1;audio-beat-detection;163;139;137;183;130;131;153;138;188;133 18:43:08 INFO - PROCESS | 5780 | |2;audio-dft;162;162;161;161;159;160;159;161;160;160 18:43:08 INFO - PROCESS | 5780 | |3;audio-fft;77;76;77;109;75;76;75;75;97;75 18:43:08 INFO - PROCESS | 5780 | |4;audio-oscillator;85;87;111;85;85;85;128;85;83;127 18:43:08 INFO - PROCESS | 5780 | |5;imaging-gaussian-blur;117;117;116;116;116;116;117;117;117;116 18:43:08 INFO - PROCESS | 5780 | |6;imaging-darkroom;146;146;147;146;146;145;146;146;146;146 18:43:08 INFO - PROCESS | 5780 | |7;imaging-desaturate;94;95;94;94;95;95;94;95;95;94 18:43:08 INFO - PROCESS | 5780 | |8;json-parse-financial;62;63;64;63;64;64;64;63;63;65 18:43:08 INFO - PROCESS | 5780 | |9;json-stringify-tinderbox;64;65;64;64;65;65;65;66;65;65 18:43:08 INFO - PROCESS | 5780 | |10;stanford-crypto-aes;83;82;87;85;83;84;83;87;119;83 18:43:08 INFO - PROCESS | 5780 | |11;stanford-crypto-ccm;139;131;128;134;130;138;131;131;133;131 18:43:08 INFO - PROCESS | 5780 | |12;stanford-crypto-pbkdf2;170;171;169;171;171;169;168;170;170;168 18:43:08 INFO - PROCESS | 5780 | |13;stanford-crypto-sha256-iterative;72;67;67;68;66;66;65;67;67;67 18:43:08 INFO - PROCESS | 5780 | __end_tp_report 18:43:08 INFO - PROCESS | 5780 | __start_cc_report 18:43:08 INFO - PROCESS | 5780 | _x_x_mozilla_cycle_collect,1901 18:43:08 INFO - PROCESS | 5780 | __end_cc_report 18:43:08 INFO - PROCESS | 5780 | __startTimestamp1464572588952__endTimestamp 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | ------- Summary: start ------- 18:43:08 INFO - PROCESS | 5780 | Number of tests: 14 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.50 stddev:1.55 (1.4%) stddev-sans-first:1.42 18:43:08 INFO - PROCESS | 5780 | Values: 116.0 111.0 114.0 113.0 114.0 113.0 112.0 115.0 115.0 115.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:146.00 stddev:21.58 (14.8%) stddev-sans-first:22.33 18:43:08 INFO - PROCESS | 5780 | Values: 163.0 139.0 137.0 183.0 130.0 131.0 153.0 138.0 188.0 133.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 18:43:08 INFO - PROCESS | 5780 | Values: 162.0 162.0 161.0 161.0 159.0 160.0 159.0 161.0 160.0 160.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.86 (15.6%) stddev-sans-first:12.48 18:43:08 INFO - PROCESS | 5780 | Values: 77.0 76.0 77.0 109.0 75.0 76.0 75.0 75.0 97.0 75.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:85.00 stddev:18.45 (21.7%) stddev-sans-first:19.13 18:43:08 INFO - PROCESS | 5780 | Values: 85.0 87.0 111.0 85.0 85.0 85.0 128.0 85.0 83.0 127.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 18:43:08 INFO - PROCESS | 5780 | Values: 117.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 116.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 18:43:08 INFO - PROCESS | 5780 | Values: 146.0 146.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:43:08 INFO - PROCESS | 5780 | Values: 94.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 18:43:08 INFO - PROCESS | 5780 | Values: 62.0 63.0 64.0 63.0 64.0 64.0 64.0 63.0 63.0 65.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 18:43:08 INFO - PROCESS | 5780 | Values: 64.0 65.0 64.0 64.0 65.0 65.0 65.0 66.0 65.0 65.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:83.50 stddev:11.17 (13.4%) stddev-sans-first:11.72 18:43:08 INFO - PROCESS | 5780 | Values: 83.0 82.0 87.0 85.0 83.0 84.0 83.0 87.0 119.0 83.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:132.00 stddev:3.50 (2.7%) stddev-sans-first:2.85 18:43:08 INFO - PROCESS | 5780 | Values: 139.0 131.0 128.0 134.0 130.0 138.0 131.0 131.0 133.0 131.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 18:43:08 INFO - PROCESS | 5780 | Values: 170.0 171.0 169.0 171.0 171.0 169.0 168.0 170.0 170.0 168.0 18:43:08 INFO - PROCESS | 5780 | 18:43:08 INFO - PROCESS | 5780 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.87 (2.8%) stddev-sans-first:0.87 18:43:08 INFO - PROCESS | 5780 | Values: 72.0 67.0 67.0 68.0 66.0 66.0 65.0 67.0 67.0 67.0 18:43:08 INFO - PROCESS | 5780 | -------- Summary: end -------- 18:43:08 INFO - PROCESS | 5780 | 18:43:09 INFO - TEST-INFO | 5780: exit 0 18:43:09 INFO - TEST-OK | kraken | took 63133ms 18:43:09 INFO - SUITE-END | took 1181s 18:43:10 INFO - Completed test suite (00:19:41) 18:43:10 INFO - javascript benchmark 18:43:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2669.6929853066895, 2676.518226719192, 2700.675918889733, 2709.972900270997, 2723.278331242221, 4642.094791515656, 4709.529047095289, 4711.76441177941, 4795.3526273953, 4846.151923413402, 4718.150732868083, 4751.667383283171, 4765.666403351758, 4772.308015337774, 4773.260144677572, 4161.916761664765, 4319.71921825082, 4412.624926881219, 4435.534268901767, 4511.368408422823, 4409.4708634963845, 4451.532589078149, 4467.10657868426, 4472.284434490482, 4504.909901801962, 4253.787310634456, 4284.871453856389, 4316.719413238129, 4323.092150648367, 4364.694471387004, 4308.138372325548, 4327.415798867144, 4369.235383807821, 4391.29739241723, 4396.868093957187, 4380.364847097153, 4381.605655491021, 4383.32058530925, 4392.736435813861, 4408.7354758714255, 4548.56788605082, 4625.051864367797, 4696.765161741933, 4698.459677137104, 4766.76166191689, 5150.922736158961, 5156.845294641167, 5170.715610641413, 5173.146430838907, 5174.068667639824, 5032.547070763612, 5162.664426812283, 5192.792288308463, 5196.428392876781, 5199.14214154664, 4735.644826637981, 4770.952290477086, 4779.832705855279, 4790.664653474223, 4825.276208568743, 5304.7347632618985, 5319.946800531945, 5323.653962492425, 5325.174597937326, 5326.547243484347, 4914.115459217303, 5087.465816089331, 5108.744562771846, 5109.642325037212, 5110.182370820651, 5050.0657378384385, 5071.974640126849, 5106.540411362995, 5129.717865517432, 5135.2553879687985, 5092.567131793839, 5097.719625420637, 5100.056489549445, 5104.515071068243, 5152.149895267239, 4896.583790377857, 4973.726445045557, 5077.543021128116, 5082.33929589151, 5091.516305950929, 4360.65114790816, 4368.060866913629, 4385.890352741207, 4428.31361139024, 4431.578999994996, 3805.771653700787, 3814.6757525610633, 3821.3694740368082, 3834.7890866002635, 3852.4221366794645, 4292.7424354538825, 4356.324769660708, 4443.111377724468, 4444.733316000986, 4476.4180656514445, 5100.38795344561, 5109.565686916654, 5142.331496905454, 5143.27994080829, 5197.29836472079, 4411.536788637147, 4414.668899832525, 4415.757133357632, 4465.129299786576, 4487.6634252431195, 4504.662150338672, 4571.954280457219, 4615.884602884955, 4632.235681112578, 4635.884102897455, 4831.73425461603, 4843.927341089816, 4850.054239423349, 4883.926741098886, 4897.5592196701045, 5129.7691603877165, 5140.717260550706, 5142.742862856917, 5144.459831717616, 5147.176451767699, 4696.178168820375, 4748.762561871962, 4798.0403919217, 4802.183628783046, 4866.707997520019, 5011.4487406386, 5020.221865610836, 5024.020316038337, 5028.823991160292, 5068.467810879804, 5103.668261562986, 5113.923291150561, 5114.053900028507, 5118.769655365592, 5130.51260130287, 4851.490593487633, 4881.121398148225, 4890.266460030882, 4907.312976183266, 4968.22992436173, 4927.802887884444, 4957.677750946177, 4958.0, 4960.7767650456535, 5018.698878067327, 4760.523947605212, 4884.853454396374, 4904.485029071896, 4908.803647854189, 4961.156603377362, 4934.605231581537, 4986.775595098156, 4995.900081998267, 4997.675151115166, 5021.673591216705, 4890.6332025097545, 4895.0, 4898.2652602109965, 4927.384076990376, 4950.133726597903], "value": 4691.3056837818785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.0598055072983, 599.4305409860633, 601.332520901799, 604.7732100462326, 612.3570251236201, 27636.723632763667, 30444.54333185001, 39788.0, 41868.371974420406, 41869.0, 42807.571924280746, 43502.56497435024, 43517.34723979143, 43519.129617407634, 43642.56357436425, 7799.92200077999, 7925.643346049442, 7971.163027882076, 7971.441999060068, 7973.880391794128, 7857.960710196469, 7954.403419743513, 7998.200179982014, 8002.319802816767, 8004.479708818937, 8016.63925123368, 8070.515769053846, 8072.475289106219, 8073.31376832967, 8076.9192308077045, 7939.36485081195, 7966.362690984736, 7991.760247192594, 7992.200779922019, 7994.160613135653, 7853.489523180975, 7944.2850143486785, 7944.602769861542, 7948.165442628557, 7948.483348582381, 38702.032449188715, 39219.80390098031, 39227.60772392268, 39235.0, 39241.60758392408, 3097.4734295169874, 3195.2491164576304, 3217.581714377139, 3471.687548120656, 3772.7547709399078, 29438.411231775244, 29976.0, 30065.699343006727, 30080.0, 31051.534226986612, 3445.8277086145845, 3464.72282217422, 3481.9651803482147, 3488.5464889564446, 3551.875684351061, 246111.0, 246850.0, 247105.0, 247959.0, 248297.0, 44961.55038449639, 53260.201096983576, 53672.73163634157, 53701.731491342296, 53963.460365396626, 54995.72502137544, 56878.43121568731, 56915.430845691844, 61175.69412152911, 61550.38449615536, 12986.285754283415, 13165.736685266242, 13213.339333033502, 13226.140300880412, 13232.801507977389, 9131.223845973034, 9501.23990080792, 9616.134547890724, 9690.70927872165, 9692.369995950381, 15639.296231669603, 15965.760513592306, 16006.439774607832, 16207.351705931867, 16355.427560035341, 5670.193254339046, 5726.513246374022, 5736.77052917887, 5789.131630255495, 5876.471117599351, 793.9563324017118, 813.7436707437139, 957.3681370295571, 958.9089036541517, 1021.8160731068479, 28353.858230709127, 28859.422811543653, 28866.855665721538, 28925.276868078465, 29033.85483072571, 879.5338470610586, 918.2516249256835, 918.5269586163131, 951.5908159491485, 981.842905135175, 14657.853421465863, 15000.249987500581, 15220.238988050774, 15305.158216297988, 15351.923240383727, 361545.0, 590788.0, 605267.0, 606210.0, 606627.0, 51543.226851597254, 51605.22592161121, 52166.21750673743, 52190.47809521932, 52218.738906305225, 1070.2454769387718, 1146.9082473402275, 1213.2538488829307, 1230.7846126927752, 1249.7125661097998, 25405.0, 29009.274768130967, 29155.125346239645, 29452.410951781272, 29485.0, 42211.3668294982, 42581.0, 42618.360724588536, 42695.57304426916, 42765.78617106895, 295201.0, 295666.0, 296196.0, 296289.0, 296618.0, 22343.77656223417, 22900.31299061031, 23143.074277029405, 23143.652845206998, 24389.634155487325, 43426.348604771585, 43445.0, 44053.33919991267, 44205.77897110623, 44579.55420445754, 36724.81637591902, 37182.83945864632, 37889.2422151561, 38080.0, 38438.807805960794, 36294.63705362913, 36824.26351473009, 36885.63114368822, 37027.444588330654, 37072.0, 33940.0, 34240.657593424745, 34501.82749086339, 36570.26859462849, 36986.8150659245, 32231.194220144684, 32656.183595410304, 33058.83470582632, 33285.0, 33443.49834752432, 25226.243212703648, 25320.366990825376, 26129.346766330993, 27422.177334679993, 27608.861955690092], "value": 17616.045608988046, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.75266324226016, 379.624172069651, 380.9200067985723, 394.30995757424506, 395.3575439910146, 16235.0, 16664.083475408846, 16952.237149328295, 17019.659606807858, 17086.0, 16329.83670163298, 16490.0, 17172.227249773758, 17224.741628875578, 17239.91380043101, 4743.620510359172, 4862.902741945177, 4895.730734809584, 4903.289023091649, 4912.410510738699, 3561.3233485637775, 3712.51737274154, 3991.341428664267, 5167.7932882684645, 6177.3822617738315, 6011.639301641893, 6141.0, 6176.938230617704, 6214.627122372672, 6230.813075607739, 5487.2317875497465, 5684.0, 5691.573132015115, 5859.648421094748, 6125.846853828668, 3463.0995941055508, 5097.643164978453, 5442.428545002757, 5452.972735136299, 5456.727163641842, 12401.25592464455, 12423.3788310585, 12454.81317780234, 12534.310612916286, 17644.735328970077, 1758.7273972534279, 1768.938087166956, 1864.114545590847, 2151.429871084148, 2309.226409152919, 8973.955130224373, 9075.36472446929, 9332.813343733087, 9351.76620584484, 9546.713598592054, 1655.4371513685405, 1661.4849396687066, 1688.4512533426685, 1693.2126561149125, 1735.9305627774875, 64477.677611611645, 64982.025269620994, 66555.66722166407, 66747.3325266746, 67560.0, 9652.90347096534, 9850.458224797561, 9879.0, 9923.751906202402, 9980.201583873273, 13180.011499137603, 13214.273214973076, 13248.403821827847, 13257.204567725967, 13275.535356262484, 6794.0, 6912.205096413876, 6919.550229235084, 7004.684789184499, 7022.332878376546, 6410.711517981702, 6490.935090649127, 6561.573497722633, 6565.474762019027, 6592.604443733296, 4200.579942005775, 4214.704970652025, 4242.427272318259, 4350.173467041315, 4423.1153769246275, 1673.305578185064, 1685.1237356574513, 1698.4395149600603, 1744.8691348148939, 1763.7530745695612, 855.1448551448551, 893.3657103456619, 906.4108329585822, 933.4446004627235, 956.2350119904049, 15717.371305147895, 16673.49979500617, 17168.22742976569, 17338.219780109925, 17450.12749362552, 828.7928017995501, 857.3098655582316, 890.7149712092069, 902.7923577577062, 915.1397686174975, 9302.53487325631, 9670.854937175807, 9830.803383932423, 9873.111419972094, 9875.209983201326, 381914.0, 382270.0, 383133.0, 392078.0, 394654.0, 57661.0, 73314.26685733075, 76251.0, 78470.6076469614, 80718.0, 831.5179190117494, 902.702108304258, 908.6955869783696, 937.5265490927038, 939.0281059103795, 13357.732845343233, 14968.0, 15026.098434093989, 15749.842501574838, 16136.205767856782, 29562.0, 30232.395352093274, 34911.476327854594, 35077.0, 35457.64542354647, 44443.555564443945, 46318.30522542232, 46452.0, 46709.532904671876, 47230.291545626154, 7690.307872291521, 7695.730649427467, 7776.183500732342, 7808.804779880548, 7929.603519823871, 8332.916670833214, 10297.74255643615, 11401.828972565252, 11479.598214062467, 11485.770284594428, 9116.954415227881, 9472.668456603986, 9671.516424178622, 9675.612975480903, 9909.950450247703, 9616.807663846825, 9848.113669769755, 9909.108180263811, 10025.097741203326, 10238.846417303597, 9406.717798466058, 9793.363431376938, 9916.256280778825, 10013.19894408431, 10177.542010609688, 7545.471816972759, 7577.1665116836, 7606.885896711443, 7668.348190403878, 7734.419918506022, 7672.194419585863, 8792.956035220039, 9998.750031249278, 10003.699889003341, 10033.996600340199], "value": 7908.56158833101, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1220.5789002794045, 1402.3479082226766, 1442.956711298661, 1560.2588770334085, 1764.488298393466, 6841.965790171054, 7451.478396512246, 7679.769606911795, 7763.417743669219, 7943.0, 7553.509021913572, 7683.654235559399, 7690.346320562745, 7703.191164927686, 7703.807404814883, 2151.623465893474, 2176.7170267865235, 2177.2053200581754, 2178.5098352870637, 2181.7381914170323, 2118.7139736135655, 2142.6893100500497, 2178.237616834111, 2180.6401943679275, 2181.4437318483842, 2149.312220089572, 2172.913083476659, 2178.2267295110246, 2179.498715295483, 2182.061713463218, 2106.483911441699, 2108.630989576833, 2110.293051827632, 2110.6728457089175, 2114.0381126587367, 2057.8971051447515, 2123.6495978163584, 2132.4882028313095, 2139.0801955159277, 2141.0, 7657.502262352929, 7744.574048427335, 7783.299503044753, 7791.727289544839, 7802.843943121163, 4470.128324976631, 4566.360709500673, 4621.329907163443, 4625.976870115628, 4627.259638457831, 2955.0543825975697, 2956.52695568708, 3034.7723920706035, 3037.407705497429, 3040.3919216156637, 961.5721004153155, 981.5582987655583, 993.8012397520525, 998.825205589019, 1004.3873237325225, 10554.525046372932, 10787.137029037658, 10819.567217311298, 10821.83767243492, 10858.022777950022, 4248.320268756984, 4393.868183954486, 4482.238019536687, 4482.820687172477, 4531.504603480844, 8372.49765014089, 8382.832343353099, 8412.831743365221, 8423.915760842436, 8427.452215605967, 4483.349914262414, 4485.910281794346, 4488.236999710057, 4490.146872094291, 4495.617872480811, 3592.604813470516, 3808.5429748430365, 4148.398482220061, 4163.354680024601, 4166.166766646683, 3425.1950791563963, 3489.633588473174, 3551.6093229744706, 3577.856885724542, 3683.9447408288897, 1019.9898001020043, 1025.8102251083574, 1028.7654814222813, 1036.1762398892897, 1037.3620223562605, 863.8229163021565, 869.1742844297942, 869.4652788535005, 916.0701887584107, 929.990700093004, 5149.407818100892, 5310.548603368679, 5433.157860531624, 5434.076207044812, 5472.2338872557875, 418.13446166435796, 419.2662840029948, 428.18644575307167, 428.3189728331905, 439.35195586509644, 3620.2397496526023, 3652.945205821915, 3672.4307732301536, 3676.1361080146144, 3723.3484140275345, 9526.237900967906, 9584.616615335308, 9845.852312215322, 9867.309288349747, 9985.350952188084, 9861.852072218922, 9870.0, 9872.851907221397, 9881.851772223423, 9905.20758339331, 6982.1272340957385, 7267.0, 7419.517731347445, 7530.021097257431, 7534.585597792065, 5643.71781410936, 5653.293338332708, 5675.177099320658, 5752.856178595569, 5884.029135192816, 8897.199252067345, 8916.242119419921, 9097.0, 9100.59047342858, 9112.362134650775, 8940.418872773249, 9124.726258212264, 9138.497382644018, 9145.085491450802, 9203.953980230055, 6291.874162516816, 6511.609303441809, 6522.15212022434, 6659.900101498384, 6660.2673705893285, 7175.928240717526, 7373.483856130019, 7382.9630851845395, 7430.888536672062, 7444.0, 6695.631740254333, 6794.218664853407, 6827.351401616838, 6936.861262774817, 6965.930340696528, 6661.566998145105, 6692.230393504809, 6716.126903502513, 6758.831029224309, 6807.0810440589885, 6125.724342404692, 6188.81433556994, 6299.905501417574, 6534.901976470452, 6538.1500404947055, 4460.821567137278, 4552.704074235164, 4612.031473390625, 4653.627709783277, 4840.056189043103, 4570.651657761035, 4627.884302892455, 4668.836590719308, 4727.432708074915, 4779.5220477951925], "value": 4060.7178207999596, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.18935173872063, 312.518721169399, 315.21354221218064, 315.9668234835342, 321.3492677328411, 4792.6165906727465, 4818.494058123894, 4838.733869637169, 4845.958119004414, 4885.487023862488, 4771.351899695667, 4789.856304310868, 4816.855494335166, 4817.494163112875, 4827.131116399047, 3588.8743893963715, 3612.837422316002, 3700.760245317822, 3731.496248006525, 3774.2828862516035, 3709.9072523187, 3713.2573485303046, 3716.9628303716886, 3738.6448287412654, 3757.549094108711, 3550.289942011608, 3661.7986010769396, 3695.778253304797, 3717.405215165574, 3729.7762134271893, 3616.8914932552066, 3722.981385093071, 3753.868614598476, 3758.9436158457647, 3771.69826413889, 3343.3814744272386, 3351.0, 3360.6639336066196, 3364.9831750841336, 3385.8984230473125, 4506.707064040859, 4535.297028960516, 4576.290674945356, 4595.333676616872, 4596.747178905159, 4098.938515922264, 4236.394617310363, 4248.638865696419, 4273.166732487166, 4349.608535231812, 4946.802127914879, 4950.975245123751, 4988.87527811804, 4992.229312731432, 5003.498950314891, 3670.5595328560476, 3670.926581468356, 3681.7054635629083, 3718.1076541630086, 3723.1622884850963, 4957.454679985199, 5156.685045313458, 5170.715610641413, 5183.170692689189, 5203.271541984087, 3689.6679298863232, 3690.3357395669036, 3702.4816525686424, 3706.4440333949583, 3724.1434470071495, 3762.2851658185405, 3775.339315619756, 3776.5090538229856, 3776.6962153086597, 3781.829817658157, 4277.123189746095, 4277.914441711148, 4293.97853010733, 4300.2904520754355, 4321.58944900234, 4781.42622885256, 4914.36113305268, 4949.7030178189425, 4969.27945447908, 5063.721495317719, 1301.7006088599676, 1309.5154792726562, 1325.8806707396188, 1327.7875539913762, 1331.5672406467936, 856.4390324337569, 864.8400045991511, 886.2555453419159, 902.3999040637902, 902.5487256371814, 4361.323994780814, 4367.497737760134, 4390.4511936008, 4403.735775853395, 4416.845410410684, 5077.720725360066, 5079.593632509391, 5099.69401835891, 5105.795768169231, 5166.0, 4253.829846806155, 4373.147236288957, 4382.430284063115, 4390.651001714132, 4429.75636339998, 2698.865056747155, 2711.3628297350106, 2722.945541089167, 2722.9727702723117, 2724.768394686474, 4807.591354734816, 4811.326414302001, 4817.2774083887, 4825.0, 4840.322354870321, 5034.974825125851, 5140.460251673595, 5152.9742351288005, 5196.220566914993, 5208.921866172011, 4761.023990081955, 4770.594499467584, 4791.52084791532, 4795.592374648194, 4809.78355973982, 5015.322931404214, 5016.297718319365, 5051.974740126276, 5054.079449809049, 5059.797608095635, 4733.3373327734835, 4924.0, 4976.129177393869, 4976.651634385558, 4989.151844186569, 4771.5467030632035, 4794.808207671654, 4802.687825291345, 4828.855134345975, 4840.1529732296, 3666.431703086026, 3685.212671854202, 3694.9076273093387, 3702.4076147817295, 3707.4624179494363, 4926.458089610069, 5036.294918711456, 5046.4701206374275, 5134.974325128351, 5241.790328386822, 4949.82675606352, 4971.676841005323, 5015.0, 5038.370203724535, 5048.242763585492, 4988.5260900214425, 5004.574611158092, 5019.0, 5036.748162591929, 5046.369203849519, 4848.515148485264, 4938.975305123451, 4970.130227210151, 4973.179425394769, 4992.975035124801, 4790.592799611933, 4879.4876537963, 5013.197888337849, 5037.823676171316, 5044.394672639307, 4907.656464047483, 4912.975435122801, 4939.185034469272, 4977.228529577921, 4978.128827455109], "value": 3781.540616370998, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.4242409183521, 671.8757029949459, 683.9897401538975, 686.3548264631246, 716.9964150179248, 12402.379881005947, 13163.07858449909, 13168.209907405539, 13208.7358252835, 13222.338883055856, 12302.077344199199, 12344.814827777567, 12351.444185011673, 12352.382380880965, 12353.876461235384, 613.619555875358, 761.3946912204797, 776.216021817965, 791.4143533784986, 792.3938187286731, 794.6543253684665, 797.9281864632181, 798.4410912361341, 798.9760307190764, 800.9599520024004, 837.4389159263308, 841.6296829395077, 846.547097302943, 848.101012926297, 848.9278411335044, 645.983850403739, 809.3323008517938, 830.5016989806068, 839.2866063845744, 839.5844057191699, 610.633619828104, 611.2023808929346, 611.2542697908544, 611.4191518057819, 611.8837420890017, 12111.0, 12202.328871912041, 12211.938940305241, 12233.20484168535, 12256.0, 11896.940515297454, 12189.0, 12234.204776689576, 12308.81536776949, 12317.815232771516, 11236.775264494665, 11244.212905096647, 11263.887361126448, 11287.757554003463, 11299.096072314194, 4021.7184797063924, 4055.8174882130374, 4069.694772892045, 4109.91780164398, 4178.185253875496, 44314.77842610766, 44716.0, 44996.55003449989, 45083.323750143776, 45109.323360149625, 9644.421334719938, 9804.656837010813, 9896.10935015852, 9949.950250248703, 10041.246906482043, 16768.245428955724, 16813.07528085967, 16945.15274236308, 16948.661026779642, 16988.49034528966, 6359.491240700733, 6579.374959378852, 6624.304448032984, 6680.665966701646, 6693.263740988487, 3673.9265214696093, 3685.0971511979737, 3709.2396058808417, 3721.5906250312446, 3851.5955824638568, 2766.3775650478483, 2870.9138725838257, 2871.612332335108, 2872.3250036241466, 2872.3967966727223, 520.5627273090622, 560.5431573267855, 585.2274997003999, 594.2007999241014, 604.1903848842484, 478.2443738892611, 527.2328761651787, 590.3624085987124, 616.2204810914197, 633.4330773957282, 6792.490563207779, 7647.120581133129, 7687.154413014676, 7750.341220996165, 7799.844003119906, 503.5493233555947, 550.1939658400382, 601.5442628838134, 610.9755609775648, 616.2235583165244, 3536.681698647134, 3671.853125874935, 3721.5534135903863, 3727.7763334200035, 3749.3251214781603, 55551.72224138853, 56435.0, 56612.43387566072, 56882.43117568854, 56889.43110568924, 49053.75473122683, 49563.50436495661, 49831.0, 50733.74633126811, 51287.230691539655, 45287.77356113264, 46349.536504634525, 46721.0, 47020.764896175984, 47101.0, 7055.18865330473, 7243.420526357773, 7419.183889772229, 7454.515456495311, 7547.924520754942, 27477.0, 28233.43533129367, 28871.56692649654, 31222.531662024634, 31654.0, 38658.22683546257, 39376.0, 39800.0, 40325.79837100796, 40361.79819100886, 10326.948365258126, 10614.257002009785, 10677.0, 10972.396518191576, 11205.60780372683, 11839.230450020721, 12167.817482737943, 12345.259284442962, 12842.293673848028, 12862.163959342613, 10724.624638137628, 10914.454277286262, 11120.61077862271, 11300.43497825089, 11367.431628418712, 10584.523696433525, 10743.516541755484, 10772.138228941509, 10774.892251077388, 11363.090952723596, 10884.129269658566, 10910.0, 11005.0, 11005.889941100486, 11315.151363647925, 9057.902725009113, 9139.177474027369, 9184.908150918405, 9487.478188699688, 9534.904650953678, 8921.15249051339, 9007.729768106967, 9095.317851161057, 9217.539123043687, 9233.158919263356], "value": 5965.327004384537, "unit": "score"}], "name": "dromaeo_css", "value": 6254.740111903277, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 114.0, 113.0, 114.0, 113.0, 112.0, 115.0, 115.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 139.0, 137.0, 183.0, 130.0, 131.0, 153.0, 138.0, 188.0, 133.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 159.0, 160.0, 159.0, 161.0, 160.0, 160.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 109.0, 75.0, 76.0, 75.0, 75.0, 97.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 111.0, 85.0, 85.0, 85.0, 128.0, 85.0, 83.0, 127.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 64.0, 63.0, 64.0, 64.0, 64.0, 63.0, 63.0, 65.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 87.0, 85.0, 83.0, 84.0, 83.0, 87.0, 119.0, 83.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 131.0, 128.0, 134.0, 130.0, 138.0, 131.0, 131.0, 133.0, 131.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 169.0, 171.0, 171.0, 169.0, 168.0, 170.0, 170.0, 168.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 67.0, 68.0, 66.0, 66.0, 65.0, 67.0, 67.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1543.5, "alertThreshold": 2.0}]} 18:43:10 INFO - Return code: 0 18:43:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:43:10 ERROR - # TBPL SUCCESS # 18:43:10 INFO - Running post-action listener: _resource_record_post_action 18:43:10 INFO - ##### 18:43:10 INFO - ##### Finished run-tests step (success) 18:43:10 INFO - ##### 18:43:10 INFO - Running post-run listener: _resource_record_post_run 18:43:10 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 521883648; Read time: 12; Write time: 540800 18:43:10 INFO - TinderboxPrint: CPU usage
13.1% 18:43:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:43:10 INFO - TinderboxPrint: I/O write bytes / time
521,883,648 / 540,800 18:43:10 INFO - TinderboxPrint: CPU idle
8,316.2 (86.9%) 18:43:10 INFO - TinderboxPrint: CPU user
1,210.6 (12.6%) 18:43:10 INFO - TinderboxPrint: Swap in / out
0 / 0 18:43:10 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 243163136; Read time: 0; Write time: 329992 18:43:10 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 278720512; Read time: 12; Write time: 210808 18:43:10 INFO - Running post-run listener: _upload_blobber_files 18:43:10 INFO - Blob upload gear active. 18:43:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:43:10 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 18:43:10 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'] 18:43:10 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 18:43:11 INFO - (blobuploader) - INFO - Open directory for files ... 18:43:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:43:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:15 INFO - (blobuploader) - INFO - Done attempting. 18:43:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:43:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:43:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:16 INFO - (blobuploader) - INFO - Done attempting. 18:43:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:43:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:43:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:17 INFO - (blobuploader) - INFO - Done attempting. 18:43:17 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:17 INFO - Return code: 0 18:43:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/076d7bdfb07ae4b2ebe4a7c3f5225b776fa6b7d7989d50eac1ee03066d4ed7556285f163a793aaa53d534dfb3b2d86b1144e55eaf5fdb01e15bfaad244a23e52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18edb9054dc149e6f794d927040a700f496cf1ed483b31ec7808e1ce587efa9a786b2f04bf6d65a7499d34274e6b984e6e535a8b8f8625c793c114a4319b9e16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e1fd8dea612fad9f26565235367b94382fe99c523f5ac363d5bac1c39a25ee6882afb0580ab00d68034feaed1c7696ee707e4d1943dc607eb99582cf0f05dfe"} 18:43:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:17 INFO - Contents: 18:43:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/076d7bdfb07ae4b2ebe4a7c3f5225b776fa6b7d7989d50eac1ee03066d4ed7556285f163a793aaa53d534dfb3b2d86b1144e55eaf5fdb01e15bfaad244a23e52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18edb9054dc149e6f794d927040a700f496cf1ed483b31ec7808e1ce587efa9a786b2f04bf6d65a7499d34274e6b984e6e535a8b8f8625c793c114a4319b9e16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e1fd8dea612fad9f26565235367b94382fe99c523f5ac363d5bac1c39a25ee6882afb0580ab00d68034feaed1c7696ee707e4d1943dc607eb99582cf0f05dfe"} 18:43:17 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:17 INFO - Copying logs to upload dir... 18:43:17 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.007006 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-05-29 18:43:15.756173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 18:43:15.759912) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 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/076d7bdfb07ae4b2ebe4a7c3f5225b776fa6b7d7989d50eac1ee03066d4ed7556285f163a793aaa53d534dfb3b2d86b1144e55eaf5fdb01e15bfaad244a23e52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18edb9054dc149e6f794d927040a700f496cf1ed483b31ec7808e1ce587efa9a786b2f04bf6d65a7499d34274e6b984e6e535a8b8f8625c793c114a4319b9e16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e1fd8dea612fad9f26565235367b94382fe99c523f5ac363d5bac1c39a25ee6882afb0580ab00d68034feaed1c7696ee707e4d1943dc607eb99582cf0f05dfe"} build_url:https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/CssibVonT1Cd0H_NhwaPPg/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/076d7bdfb07ae4b2ebe4a7c3f5225b776fa6b7d7989d50eac1ee03066d4ed7556285f163a793aaa53d534dfb3b2d86b1144e55eaf5fdb01e15bfaad244a23e52", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18edb9054dc149e6f794d927040a700f496cf1ed483b31ec7808e1ce587efa9a786b2f04bf6d65a7499d34274e6b984e6e535a8b8f8625c793c114a4319b9e16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7e1fd8dea612fad9f26565235367b94382fe99c523f5ac363d5bac1c39a25ee6882afb0580ab00d68034feaed1c7696ee707e4d1943dc607eb99582cf0f05dfe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 18:43:15.788541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:43:15.788883) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1464570918.90672-2041366192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 18:43:15.872992) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 18:43:15.873350) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 18:43:15.873700) ========= ========= Total master_lag: 0.20 =========