builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-043 starttime: 1463618252.82 results: success (0) buildid: 20160518172148 builduid: 0bb574d5b1584509a76a873603e79522 revision: 370cc2de9b99588532be789e816a0fafb1e58b29 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.818692) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.819360) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.819675) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.841603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.841929) ========= 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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 17:37:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-05-18 17:37:33 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.979171) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:32.979507) ========= 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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017309 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:33.043009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 17:37:33.043693) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 370cc2de9b99588532be789e816a0fafb1e58b29 --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 370cc2de9b99588532be789e816a0fafb1e58b29 --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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 17:37:33,412 truncating revision to first 12 chars 2016-05-18 17:37:33,413 Setting DEBUG logging. 2016-05-18 17:37:33,413 attempt 1/10 2016-05-18 17:37:33,413 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/370cc2de9b99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 17:37:34,145 unpacking tar archive at: fx-team-370cc2de9b99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.023098 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 17:37:34.080621) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:34.080923) ========= script_repo_revision: 370cc2de9b99588532be789e816a0fafb1e58b29 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:34.081467) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:34.081770) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 17:37:34.091244) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-18 17:37:34.091624) ========= /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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:37:34 INFO - MultiFileLogger online at 20160518 17:37:34 in /builds/slave/test 17:37:34 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 17:37:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:34 INFO - {'append_to_log': False, 17:37:34 INFO - 'base_work_dir': '/builds/slave/test', 17:37:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:37:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:34 INFO - 'branch': 'Fx-Team-Non-PGO', 17:37:34 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:34 INFO - 'config_files': ('talos/linux_config.py',), 17:37:34 INFO - 'default_actions': ('clobber', 17:37:34 INFO - 'read-buildbot-config', 17:37:34 INFO - 'download-and-extract', 17:37:34 INFO - 'populate-webroot', 17:37:34 INFO - 'create-virtualenv', 17:37:34 INFO - 'install', 17:37:34 INFO - 'run-tests'), 17:37:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:34 INFO - 'download_minidump_stackwalk': True, 17:37:34 INFO - 'download_symbols': 'ondemand', 17:37:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:34 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:34 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:34 INFO - 'installer_path': 'installer.exe', 17:37:34 INFO - 'log_level': 'info', 17:37:34 INFO - 'log_name': 'talos', 17:37:34 INFO - 'log_to_console': True, 17:37:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:37:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:37:34 INFO - 'opt_config_files': (), 17:37:34 INFO - 'pip_index': False, 17:37:34 INFO - 'sps_profile': False, 17:37:34 INFO - 'sps_profile_interval': 0, 17:37:34 INFO - 'suite': 'dromaeojs', 17:37:34 INFO - 'system_bits': '32', 17:37:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:34 INFO - 'title': 'talos-linux64-ix-043', 17:37:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:34 INFO - 'use_talos_json': True, 17:37:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:34 INFO - 'work_dir': 'build'} 17:37:34 INFO - ##### 17:37:34 INFO - ##### Running clobber step. 17:37:34 INFO - ##### 17:37:34 INFO - Running pre-action listener: _resource_record_pre_action 17:37:34 INFO - Running main action method: clobber 17:37:34 INFO - rmtree: /builds/slave/test/build 17:37:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:35 INFO - Running post-action listener: _resource_record_post_action 17:37:35 INFO - ##### 17:37:35 INFO - ##### Finished clobber step (success) 17:37:35 INFO - ##### 17:37:35 INFO - ##### 17:37:35 INFO - ##### Running read-buildbot-config step. 17:37:35 INFO - ##### 17:37:35 INFO - Running pre-action listener: _resource_record_pre_action 17:37:35 INFO - Running main action method: read_buildbot_config 17:37:35 INFO - Using buildbot properties: 17:37:35 INFO - { 17:37:35 INFO - "project": "", 17:37:35 INFO - "product": "firefox", 17:37:35 INFO - "script_repo_revision": "production", 17:37:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:37:35 INFO - "repository": "", 17:37:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 17:37:35 INFO - "buildid": "20160518172148", 17:37:35 INFO - "pgo_build": "False", 17:37:35 INFO - "basedir": "/builds/slave/test", 17:37:35 INFO - "buildnumber": 1696, 17:37:35 INFO - "slavename": "talos-linux64-ix-043", 17:37:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:37:35 INFO - "platform": "ubuntu64_hw", 17:37:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:37:35 INFO - "branch": "fx-team", 17:37:35 INFO - "repo_path": "integration/fx-team", 17:37:35 INFO - "revision": "370cc2de9b99588532be789e816a0fafb1e58b29", 17:37:35 INFO - "stage_platform": "linux64", 17:37:35 INFO - "builduid": "0bb574d5b1584509a76a873603e79522", 17:37:35 INFO - "slavebuilddir": "test" 17:37:35 INFO - } 17:37:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:37:35 INFO - Running post-action listener: _resource_record_post_action 17:37:35 INFO - ##### 17:37:35 INFO - ##### Finished read-buildbot-config step (success) 17:37:35 INFO - ##### 17:37:35 INFO - ##### 17:37:35 INFO - ##### Running download-and-extract step. 17:37:35 INFO - ##### 17:37:35 INFO - Running pre-action listener: _resource_record_pre_action 17:37:35 INFO - Running main action method: download_and_extract 17:37:35 INFO - mkdir: /builds/slave/test/build/tests 17:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:35 INFO - https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:37:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:37:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:37:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:40 INFO - Downloaded 1361 bytes. 17:37:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:37:40 INFO - Using the following test package requirements: 17:37:40 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:37:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:37:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:37:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'jsshell-linux-x86_64.zip'], 17:37:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:37:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:37:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:37:40 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:37:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:37:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:37:40 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:37:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:37:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:40 INFO - https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:43 INFO - Downloaded 22812650 bytes. 17:37:43 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'] 17:37:43 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 17:37:44 INFO - Return code: 0 17:37:44 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 17:37: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')]} 17:37:44 INFO - https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:37:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:37:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:37:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:47 INFO - Downloaded 22812650 bytes. 17:37:47 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'] 17:37:47 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 17:37:48 INFO - Return code: 0 17:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:48 INFO - https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:37:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:37:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:37:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:49 INFO - Downloaded 10840194 bytes. 17:37:49 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'] 17:37:49 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 17:37:50 INFO - Return code: 0 17:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:50 INFO - https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:37:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:37:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:37:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H3lDrMp7QKadYpBpwyki2Q/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 17:37:52 INFO - Downloaded 54806124 bytes. 17:37:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:37:52 INFO - mkdir: /builds/slave/test/properties 17:37:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:37:52 INFO - Writing to file /builds/slave/test/properties/build_url 17:37:52 INFO - Contents: 17:37:52 INFO - build_url:https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:37:52 INFO - Running post-action listener: _resource_record_post_action 17:37:52 INFO - Running post-action listener: set_extra_try_arguments 17:37:52 INFO - ##### 17:37:52 INFO - ##### Finished download-and-extract step (success) 17:37:52 INFO - ##### 17:37:52 INFO - ##### 17:37:52 INFO - ##### Running populate-webroot step. 17:37:52 INFO - ##### 17:37:52 INFO - Running pre-action listener: _resource_record_pre_action 17:37:52 INFO - Running main action method: populate_webroot 17:37:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:37:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:37:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:37:52 INFO - '--tppagecycles', 17:37:52 INFO - '7'], 17:37:52 INFO - 'tests': ['tsvgm']}}, 17:37:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:37:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:37:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:37:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:37:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:37:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:37:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['damp', 'tps']}, 17:37:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'tests': ['damp', 'tps']}, 17:37:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['dromaeo_dom']}, 17:37:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:37:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'other-e10s': {'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['a11yr', 17:37:52 INFO - 'ts_paint', 17:37:52 INFO - 'tpaint', 17:37:52 INFO - 'sessionrestore', 17:37:52 INFO - 'sessionrestore_no_auto_restore', 17:37:52 INFO - 'tabpaint']}, 17:37:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['tsvgx', 17:37:52 INFO - 'tsvgr_opacity', 17:37:52 INFO - 'tart', 17:37:52 INFO - 'tscrollx', 17:37:52 INFO - 'cart']}, 17:37:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:37:52 INFO - 'tsvgr_opacity', 17:37:52 INFO - 'tart', 17:37:52 INFO - 'tscrollx', 17:37:52 INFO - 'cart']}, 17:37:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'talos_options': ['--disable-e10s'], 17:37:52 INFO - 'tests': ['tp5o']}, 17:37:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'tests': ['tp5o']}, 17:37:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'talos_options': ['--disable-e10s', 17:37:52 INFO - '--xperf_path', 17:37:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:37:52 INFO - 'tests': ['tp5n']}, 17:37:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:37:52 INFO - 'talos_options': ['--xperf_path', 17:37:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:37:52 INFO - 'tests': ['tp5n']}}, 17:37:52 INFO - 'talos.zip': {'path': '', 17:37:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:37:52 INFO - Running post-action listener: _resource_record_post_action 17:37:52 INFO - ##### 17:37:52 INFO - ##### Finished populate-webroot step (success) 17:37:52 INFO - ##### 17:37:52 INFO - ##### 17:37:52 INFO - ##### Running create-virtualenv step. 17:37:52 INFO - ##### 17:37:52 INFO - Running pre-action listener: _resource_record_pre_action 17:37:52 INFO - Running main action method: create_virtualenv 17:37:52 INFO - Creating virtualenv /builds/slave/test/build/venv 17:37:52 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 17:37:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:37:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:37:52 INFO - Using real prefix '/usr' 17:37:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:37:53 INFO - Installing distribute.............................................................................................................................................................................................done. 17:37:55 INFO - Installing pip.................done. 17:37:55 INFO - Return code: 0 17:37:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:37:55 INFO - Using env: {'DISPLAY': ':0', 17:37:55 INFO - 'HOME': '/home/cltbld', 17:37:55 INFO - 'LANG': 'en_US.UTF-8', 17:37:55 INFO - 'LANGUAGE': 'en_US:en', 17:37:55 INFO - 'LOGNAME': 'cltbld', 17:37:55 INFO - 'MAIL': '/var/mail/cltbld', 17:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:55 INFO - 'MOZ_NO_REMOTE': '1', 17:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:37:55 INFO - 'NO_EM_RESTART': '1', 17:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:55 INFO - 'PWD': '/builds/slave/test', 17:37:55 INFO - 'SHELL': '/bin/bash', 17:37:55 INFO - 'SHLVL': '1', 17:37:55 INFO - 'TERM': 'linux', 17:37:55 INFO - 'TMOUT': '86400', 17:37:55 INFO - 'USER': 'cltbld', 17:37:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:55 INFO - '_': '/tools/buildbot/bin/python'} 17:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:55 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 17:37:55 INFO - Cleaning up... 17:37:55 INFO - Return code: 0 17:37:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:37:55 INFO - Using env: {'DISPLAY': ':0', 17:37:55 INFO - 'HOME': '/home/cltbld', 17:37:55 INFO - 'LANG': 'en_US.UTF-8', 17:37:55 INFO - 'LANGUAGE': 'en_US:en', 17:37:55 INFO - 'LOGNAME': 'cltbld', 17:37:55 INFO - 'MAIL': '/var/mail/cltbld', 17:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:55 INFO - 'MOZ_NO_REMOTE': '1', 17:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:37:55 INFO - 'NO_EM_RESTART': '1', 17:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:55 INFO - 'PWD': '/builds/slave/test', 17:37:55 INFO - 'SHELL': '/bin/bash', 17:37:55 INFO - 'SHLVL': '1', 17:37:55 INFO - 'TERM': 'linux', 17:37:55 INFO - 'TMOUT': '86400', 17:37:55 INFO - 'USER': 'cltbld', 17:37:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:55 INFO - '_': '/tools/buildbot/bin/python'} 17:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:55 INFO - Downloading/unpacking psutil>=3.1.1 17:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:37:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:37:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:37:57 INFO - Installing collected packages: psutil 17:37:57 INFO - Running setup.py install for psutil 17:37:57 INFO - building 'psutil._psutil_linux' extension 17:37:57 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 17:37:58 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 17:37:58 INFO - building 'psutil._psutil_posix' extension 17:37:58 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 17:37:58 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 17:37:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:37:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:37:58 INFO - Successfully installed psutil 17:37:58 INFO - Cleaning up... 17:37:58 INFO - Return code: 0 17:37:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:37:58 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:37:58 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 17:37:58 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 17:37:58 INFO - Using env: {'DISPLAY': ':0', 17:37:58 INFO - 'HOME': '/home/cltbld', 17:37:58 INFO - 'LANG': 'en_US.UTF-8', 17:37:58 INFO - 'LANGUAGE': 'en_US:en', 17:37:58 INFO - 'LOGNAME': 'cltbld', 17:37:58 INFO - 'MAIL': '/var/mail/cltbld', 17:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:37:58 INFO - 'MOZ_NO_REMOTE': '1', 17:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:37:58 INFO - 'NO_EM_RESTART': '1', 17:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:37:58 INFO - 'PWD': '/builds/slave/test', 17:37:58 INFO - 'SHELL': '/bin/bash', 17:37:58 INFO - 'SHLVL': '1', 17:37:58 INFO - 'TERM': 'linux', 17:37:58 INFO - 'TMOUT': '86400', 17:37:58 INFO - 'USER': 'cltbld', 17:37:58 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:37:58 INFO - '_': '/tools/buildbot/bin/python'} 17:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:37:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:37:58 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 17:37:58 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 17:37:58 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 17:37:58 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 17:37:58 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 17:37:58 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 17:38:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:38:00 INFO - Installing collected packages: mozsystemmonitor 17:38:00 INFO - Running setup.py install for mozsystemmonitor 17:38:00 INFO - Successfully installed mozsystemmonitor 17:38:00 INFO - Cleaning up... 17:38:00 INFO - Return code: 0 17:38:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:38:00 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')]} 17:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:00 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')]} 17:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:00 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:00 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 17:38:00 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 17:38:00 INFO - Using env: {'DISPLAY': ':0', 17:38:00 INFO - 'HOME': '/home/cltbld', 17:38:00 INFO - 'LANG': 'en_US.UTF-8', 17:38:00 INFO - 'LANGUAGE': 'en_US:en', 17:38:00 INFO - 'LOGNAME': 'cltbld', 17:38:00 INFO - 'MAIL': '/var/mail/cltbld', 17:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:00 INFO - 'MOZ_NO_REMOTE': '1', 17:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:00 INFO - 'NO_EM_RESTART': '1', 17:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:00 INFO - 'PWD': '/builds/slave/test', 17:38:00 INFO - 'SHELL': '/bin/bash', 17:38:00 INFO - 'SHLVL': '1', 17:38:00 INFO - 'TERM': 'linux', 17:38:00 INFO - 'TMOUT': '86400', 17:38:00 INFO - 'USER': 'cltbld', 17:38:00 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:00 INFO - '_': '/tools/buildbot/bin/python'} 17:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:00 INFO - Downloading/unpacking blobuploader==1.2.4 17:38:00 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 17:38:00 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 17:38:00 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 17:38:00 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 17:38:00 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 17:38:00 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 17:38:02 INFO - Downloading blobuploader-1.2.4.tar.gz 17:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:38:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:38: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 17:38: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 17:38: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 17:38: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 17:38: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 17:38: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 17:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:38:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:03 INFO - Downloading docopt-0.6.1.tar.gz 17:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:38:03 INFO - Installing collected packages: blobuploader, requests, docopt 17:38:03 INFO - Running setup.py install for blobuploader 17:38:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:38:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:38:03 INFO - Running setup.py install for requests 17:38:03 INFO - Running setup.py install for docopt 17:38:04 INFO - Successfully installed blobuploader requests docopt 17:38:04 INFO - Cleaning up... 17:38:04 INFO - Return code: 0 17:38:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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')]} 17:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:04 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:04 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 17:38:04 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 17:38:04 INFO - Using env: {'DISPLAY': ':0', 17:38:04 INFO - 'HOME': '/home/cltbld', 17:38:04 INFO - 'LANG': 'en_US.UTF-8', 17:38:04 INFO - 'LANGUAGE': 'en_US:en', 17:38:04 INFO - 'LOGNAME': 'cltbld', 17:38:04 INFO - 'MAIL': '/var/mail/cltbld', 17:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:04 INFO - 'MOZ_NO_REMOTE': '1', 17:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:04 INFO - 'NO_EM_RESTART': '1', 17:38:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:04 INFO - 'PWD': '/builds/slave/test', 17:38:04 INFO - 'SHELL': '/bin/bash', 17:38:04 INFO - 'SHLVL': '1', 17:38:04 INFO - 'TERM': 'linux', 17:38:04 INFO - 'TMOUT': '86400', 17:38:04 INFO - 'USER': 'cltbld', 17:38:04 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:04 INFO - '_': '/tools/buildbot/bin/python'} 17:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:04 INFO - Running setup.py (path:/tmp/pip-twl9FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:04 INFO - Running setup.py (path:/tmp/pip-7Lu3PC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:04 INFO - Running setup.py (path:/tmp/pip-SbNqiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:04 INFO - Running setup.py (path:/tmp/pip-161mct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:04 INFO - Running setup.py (path:/tmp/pip-g6tO27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:04 INFO - Running setup.py (path:/tmp/pip-e8OItk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:04 INFO - Running setup.py (path:/tmp/pip-7M2zWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:04 INFO - Running setup.py (path:/tmp/pip-0cIyMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:04 INFO - Running setup.py (path:/tmp/pip-PX1OyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:04 INFO - Running setup.py (path:/tmp/pip-7vGWHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:05 INFO - Running setup.py (path:/tmp/pip-FwKOA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:05 INFO - Running setup.py (path:/tmp/pip-j7hRBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:05 INFO - Running setup.py (path:/tmp/pip-f4Q3qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:05 INFO - Running setup.py (path:/tmp/pip-WalzEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:05 INFO - Running setup.py (path:/tmp/pip-2P9eX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:05 INFO - Running setup.py (path:/tmp/pip-sany8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:05 INFO - Running setup.py (path:/tmp/pip-HYLH0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:38:05 INFO - Running setup.py install for manifestparser 17:38:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:38:05 INFO - Running setup.py install for mozcrash 17:38:05 INFO - Running setup.py install for mozdebug 17:38:05 INFO - Running setup.py install for mozdevice 17:38:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:38:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:38:05 INFO - Running setup.py install for mozfile 17:38:06 INFO - Running setup.py install for mozhttpd 17:38:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozinfo 17:38:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozInstall 17:38:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozleak 17:38:06 INFO - Running setup.py install for mozlog 17:38:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for moznetwork 17:38:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozprocess 17:38:06 INFO - Running setup.py install for mozprofile 17:38:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozrunner 17:38:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:38:06 INFO - Running setup.py install for mozscreenshot 17:38:07 INFO - Running setup.py install for moztest 17:38:07 INFO - Running setup.py install for mozversion 17:38:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:38:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:38:07 INFO - Cleaning up... 17:38:07 INFO - Return code: 0 17:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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')]} 17:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:07 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:07 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 17:38:07 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 17:38:07 INFO - Using env: {'DISPLAY': ':0', 17:38:07 INFO - 'HOME': '/home/cltbld', 17:38:07 INFO - 'LANG': 'en_US.UTF-8', 17:38:07 INFO - 'LANGUAGE': 'en_US:en', 17:38:07 INFO - 'LOGNAME': 'cltbld', 17:38:07 INFO - 'MAIL': '/var/mail/cltbld', 17:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:07 INFO - 'MOZ_NO_REMOTE': '1', 17:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:07 INFO - 'NO_EM_RESTART': '1', 17:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:07 INFO - 'PWD': '/builds/slave/test', 17:38:07 INFO - 'SHELL': '/bin/bash', 17:38:07 INFO - 'SHLVL': '1', 17:38:07 INFO - 'TERM': 'linux', 17:38:07 INFO - 'TMOUT': '86400', 17:38:07 INFO - 'USER': 'cltbld', 17:38:07 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:07 INFO - '_': '/tools/buildbot/bin/python'} 17:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:07 INFO - Running setup.py (path:/tmp/pip-fHF1NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:07 INFO - Running setup.py (path:/tmp/pip-XptGlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:07 INFO - Running setup.py (path:/tmp/pip-qu1kVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:07 INFO - Running setup.py (path:/tmp/pip-vdXZLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:07 INFO - Running setup.py (path:/tmp/pip-UY0QnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:07 INFO - Running setup.py (path:/tmp/pip-sCZxHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:07 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)) 17:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:07 INFO - Running setup.py (path:/tmp/pip-3nuAT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:08 INFO - Running setup.py (path:/tmp/pip-CbBZFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:08 INFO - Running setup.py (path:/tmp/pip-hsGdVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:08 INFO - Running setup.py (path:/tmp/pip-k64rcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:08 INFO - Running setup.py (path:/tmp/pip-He38wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:08 INFO - Running setup.py (path:/tmp/pip-d4epob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:08 INFO - Running setup.py (path:/tmp/pip-3AbPY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:08 INFO - Running setup.py (path:/tmp/pip-gY34Ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:08 INFO - Running setup.py (path:/tmp/pip-hyOTEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:08 INFO - Running setup.py (path:/tmp/pip-tDZ7A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:08 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)) 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:08 INFO - Running setup.py (path:/tmp/pip-gwGIvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:08 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)) 17:38:08 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)) 17:38:08 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)) 17:38:08 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)) 17:38:08 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)) 17:38:08 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)) 17:38:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:10 INFO - Downloading blessings-1.6.tar.gz 17:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:38:10 INFO - Installing collected packages: blessings 17:38:10 INFO - Running setup.py install for blessings 17:38:10 INFO - Successfully installed blessings 17:38:10 INFO - Cleaning up... 17:38:10 INFO - Return code: 0 17:38:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:11 INFO - Reading from file tmpfile_stdout 17:38:11 INFO - Current package versions: 17:38:11 INFO - argparse == 1.2.1 17:38:11 INFO - blessings == 1.6 17:38:11 INFO - blobuploader == 1.2.4 17:38:11 INFO - docopt == 0.6.1 17:38:11 INFO - manifestparser == 1.1 17:38:11 INFO - mozInstall == 1.12 17:38:11 INFO - mozcrash == 0.17 17:38:11 INFO - mozdebug == 0.1 17:38:11 INFO - mozdevice == 0.48 17:38:11 INFO - mozfile == 1.2 17:38:11 INFO - mozhttpd == 0.7 17:38:11 INFO - mozinfo == 0.9 17:38:11 INFO - mozleak == 0.1 17:38:11 INFO - mozlog == 3.2 17:38:11 INFO - moznetwork == 0.27 17:38:11 INFO - mozprocess == 0.22 17:38:11 INFO - mozprofile == 0.28 17:38:11 INFO - mozrunner == 6.11 17:38:11 INFO - mozscreenshot == 0.1 17:38:11 INFO - mozsystemmonitor == 0.3 17:38:11 INFO - moztest == 0.7 17:38:11 INFO - mozversion == 1.4 17:38:11 INFO - psutil == 3.1.1 17:38:11 INFO - requests == 1.2.3 17:38:11 INFO - wsgiref == 0.1.2 17:38:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:11 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:11 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 17:38:11 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 17:38:11 INFO - Using env: {'DISPLAY': ':0', 17:38:11 INFO - 'HOME': '/home/cltbld', 17:38:11 INFO - 'LANG': 'en_US.UTF-8', 17:38:11 INFO - 'LANGUAGE': 'en_US:en', 17:38:11 INFO - 'LOGNAME': 'cltbld', 17:38:11 INFO - 'MAIL': '/var/mail/cltbld', 17:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:11 INFO - 'MOZ_NO_REMOTE': '1', 17:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:11 INFO - 'NO_EM_RESTART': '1', 17:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:11 INFO - 'PWD': '/builds/slave/test', 17:38:11 INFO - 'SHELL': '/bin/bash', 17:38:11 INFO - 'SHLVL': '1', 17:38:11 INFO - 'TERM': 'linux', 17:38:11 INFO - 'TMOUT': '86400', 17:38:11 INFO - 'USER': 'cltbld', 17:38:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:11 INFO - '_': '/tools/buildbot/bin/python'} 17:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 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)) 17:38:11 INFO - Cleaning up... 17:38:11 INFO - Return code: 0 17:38:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:11 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:11 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 17:38:11 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 17:38:11 INFO - Using env: {'DISPLAY': ':0', 17:38:11 INFO - 'HOME': '/home/cltbld', 17:38:11 INFO - 'LANG': 'en_US.UTF-8', 17:38:11 INFO - 'LANGUAGE': 'en_US:en', 17:38:11 INFO - 'LOGNAME': 'cltbld', 17:38:11 INFO - 'MAIL': '/var/mail/cltbld', 17:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:11 INFO - 'MOZ_NO_REMOTE': '1', 17:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:11 INFO - 'NO_EM_RESTART': '1', 17:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:11 INFO - 'PWD': '/builds/slave/test', 17:38:11 INFO - 'SHELL': '/bin/bash', 17:38:11 INFO - 'SHLVL': '1', 17:38:11 INFO - 'TERM': 'linux', 17:38:11 INFO - 'TMOUT': '86400', 17:38:11 INFO - 'USER': 'cltbld', 17:38:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:11 INFO - '_': '/tools/buildbot/bin/python'} 17:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:11 INFO - Downloading/unpacking jsonschema 17:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:38:13 INFO - Downloading/unpacking functools32 (from jsonschema) 17:38:13 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 17:38:13 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 17:38:13 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 17:38:13 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 17:38:13 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 17:38:13 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 17:38:13 INFO - Downloading functools32-3.2.3-2.tar.gz 17:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:38:13 INFO - warning: no files found matching '*.txt' 17:38:13 INFO - no previously-included directories found matching 'build' 17:38:13 INFO - no previously-included directories found matching 'dist' 17:38:13 INFO - no previously-included directories found matching '.git*' 17:38:13 INFO - Installing collected packages: jsonschema, functools32 17:38:13 INFO - Running setup.py install for functools32 17:38:13 INFO - warning: no files found matching '*.txt' 17:38:13 INFO - no previously-included directories found matching 'build' 17:38:13 INFO - no previously-included directories found matching 'dist' 17:38:13 INFO - no previously-included directories found matching '.git*' 17:38:13 INFO - Successfully installed jsonschema functools32 17:38:13 INFO - Cleaning up... 17:38:13 INFO - Return code: 0 17:38:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:13 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 0x7f0eb0377f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a7770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, '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': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', '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 17:38:13 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 17:38:13 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 17:38:13 INFO - Using env: {'DISPLAY': ':0', 17:38:13 INFO - 'HOME': '/home/cltbld', 17:38:13 INFO - 'LANG': 'en_US.UTF-8', 17:38:13 INFO - 'LANGUAGE': 'en_US:en', 17:38:13 INFO - 'LOGNAME': 'cltbld', 17:38:13 INFO - 'MAIL': '/var/mail/cltbld', 17:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:13 INFO - 'MOZ_NO_REMOTE': '1', 17:38:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:13 INFO - 'NO_EM_RESTART': '1', 17:38:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:13 INFO - 'PWD': '/builds/slave/test', 17:38:13 INFO - 'SHELL': '/bin/bash', 17:38:13 INFO - 'SHLVL': '1', 17:38:13 INFO - 'TERM': 'linux', 17:38:13 INFO - 'TMOUT': '86400', 17:38:13 INFO - 'USER': 'cltbld', 17:38:13 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:13 INFO - '_': '/tools/buildbot/bin/python'} 17:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:14 INFO - Downloading/unpacking flake8 17:38:14 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 17:38:14 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 17:38:14 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 17:38:14 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 17:38:14 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 17:38:14 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 17:38:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:38:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:38:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:38:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:38:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:38:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:38:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:38:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:38:16 INFO - no previously-included directories found matching 'docs/_build' 17:38:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:38:16 INFO - Running setup.py install for pep8 17:38:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:38:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:38:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:38:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:38:16 INFO - no previously-included directories found matching 'docs/_build' 17:38:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:38:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:38:16 INFO - Cleaning up... 17:38:17 INFO - Return code: 0 17:38:17 INFO - Running post-action listener: _resource_record_post_action 17:38:17 INFO - Running post-action listener: _start_resource_monitoring 17:38:17 INFO - Starting resource monitoring. 17:38:17 INFO - ##### 17:38:17 INFO - ##### Finished create-virtualenv step (success) 17:38:17 INFO - ##### 17:38:17 INFO - ##### 17:38:17 INFO - ##### Running install step. 17:38:17 INFO - ##### 17:38:17 INFO - Running pre-action listener: _resource_record_pre_action 17:38:17 INFO - Running main action method: install 17:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:17 INFO - Reading from file tmpfile_stdout 17:38:17 INFO - Detecting whether we're running mozinstall >=1.0... 17:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:38:17 INFO - Reading from file tmpfile_stdout 17:38:17 INFO - Output received: 17:38:17 INFO - Usage: mozinstall [options] installer 17:38:17 INFO - Options: 17:38:17 INFO - -h, --help show this help message and exit 17:38:17 INFO - -d DEST, --destination=DEST 17:38:17 INFO - Directory to install application into. [default: 17:38:17 INFO - "/builds/slave/test"] 17:38:17 INFO - --app=APP Application being installed. [default: firefox] 17:38:17 INFO - mkdir: /builds/slave/test/build/application 17:38:17 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'] 17:38:17 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 17:38:33 INFO - Reading from file tmpfile_stdout 17:38:33 INFO - Output received: 17:38:33 INFO - /builds/slave/test/build/application/firefox/firefox 17:38:33 INFO - Running post-action listener: _resource_record_post_action 17:38:33 INFO - ##### 17:38:33 INFO - ##### Finished install step (success) 17:38:33 INFO - ##### 17:38:33 INFO - ##### 17:38:33 INFO - ##### Running run-tests step. 17:38:33 INFO - ##### 17:38:33 INFO - Running pre-action listener: _resource_record_pre_action 17:38:33 INFO - Running main action method: run_tests 17:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:38:33 INFO - Python 2.7.3 17:38:33 INFO - Return code: 0 17:38:33 INFO - grabbing minidump binary from tooltool 17:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:38:33 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 0x17369c0>, '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 0x17378c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1737d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:38:33 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 17:38:33 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 17:38:33 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 17:38:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:38:33 INFO - Return code: 0 17:38:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:38:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:38:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:38:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:38:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:38:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:38:34 INFO - Return code: 0 17:38:34 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/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 17:38:34 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/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 17:38:34 INFO - Using env: {'DISPLAY': ':0', 17:38:34 INFO - 'HOME': '/home/cltbld', 17:38:34 INFO - 'LANG': 'en_US.UTF-8', 17:38:34 INFO - 'LANGUAGE': 'en_US:en', 17:38:34 INFO - 'LOGNAME': 'cltbld', 17:38:34 INFO - 'MAIL': '/var/mail/cltbld', 17:38:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:38:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:34 INFO - 'MOZ_NO_REMOTE': '1', 17:38:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:38:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:34 INFO - 'NO_EM_RESTART': '1', 17:38:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:34 INFO - 'PWD': '/builds/slave/test', 17:38:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:38:34 INFO - 'SHELL': '/bin/bash', 17:38:34 INFO - 'SHLVL': '1', 17:38:34 INFO - 'TERM': 'linux', 17:38:34 INFO - 'TMOUT': '86400', 17:38:34 INFO - 'USER': 'cltbld', 17:38:34 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1463616640.165885-492330048', 17:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:34 INFO - '_': '/tools/buildbot/bin/python'} 17:38:34 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/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 17:38:34 INFO - mozversion application_buildid: 20160518172148 17:38:34 INFO - mozversion application_changeset: 370cc2de9b99588532be789e816a0fafb1e58b29 17:38:34 INFO - mozversion application_display_name: Nightly 17:38:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:38:34 INFO - mozversion application_name: Firefox 17:38:34 INFO - mozversion application_remotingname: firefox 17:38:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:38:34 INFO - mozversion application_vendor: Mozilla 17:38:34 INFO - mozversion application_version: 49.0a1 17:38:34 INFO - mozversion platform_buildid: 20160518172148 17:38:34 INFO - mozversion platform_changeset: 370cc2de9b99588532be789e816a0fafb1e58b29 17:38:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:38:34 INFO - mozversion platform_version: 49.0a1 17:38:34 INFO - using testdate: 1463618314 17:38:34 INFO - actual date: 1463618314 17:38:34 INFO - starting webserver on 'localhost:56521' 17:38:34 INFO - SUITE-START | Running 2 tests 17:38:34 INFO - TEST-START | dromaeo_css 17:38:34 INFO - operating with platform_type : linux_ 17:38:34 INFO - Initialising browser for dromaeo_css test... 17:38:34 INFO - TEST-INFO | started process 3852 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg2vCXr/profile http://localhost:56521/getInfo.html) 17:38:38 INFO - PROCESS | 3852 | __metrics Screen width/height:1600/1200 17:38:38 INFO - PROCESS | 3852 | colorDepth:24 17:38:38 INFO - PROCESS | 3852 | Browser inner width/height: 1024/697 17:38:38 INFO - PROCESS | 3852 | __metrics 17:38:40 ERROR - PROCESS | 3852 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:38:40 INFO - TEST-INFO | 3852: exit 0 17:38:40 INFO - Browser initialized. 17:38:40 INFO - Running cycle 1/1 for dromaeo_css test... 17:38:40 INFO - TEST-INFO | started process 3935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg2vCXr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:38:52 INFO - PROCESS | 3935 | RSS: Main: 154226688 17:38:52 INFO - PROCESS | 3935 | 17:39:44 INFO - PROCESS | 3935 | 1463618384493 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] 17:41:56 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56521/tests/dromaeo/cssquery-ext.html) 17:41:57 INFO - PROCESS | 3935 | RSS: Main: 280780800 17:41:57 INFO - PROCESS | 3935 | 17:45:00 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-ext.html (next: http://localhost:56521/tests/dromaeo/cssquery-jquery.html) 17:45:00 INFO - PROCESS | 3935 | RSS: Main: 231198720 17:45:00 INFO - PROCESS | 3935 | 17:48:02 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56521/tests/dromaeo/cssquery-mootools.html) 17:48:03 INFO - PROCESS | 3935 | RSS: Main: 233807872 17:48:03 INFO - PROCESS | 3935 | 17:51:05 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56521/tests/dromaeo/cssquery-prototype.html) 17:51:06 INFO - PROCESS | 3935 | RSS: Main: 234831872 17:51:06 INFO - PROCESS | 3935 | 17:54:09 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56521/tests/dromaeo/cssquery-yui.html) 17:54:09 INFO - PROCESS | 3935 | RSS: Main: 209641472 17:54:09 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | Cycle 1(1): loaded http://localhost:56521/tests/dromaeo/cssquery-yui.html (next: http://localhost:56521/tests/dromaeo/cssquery-dojo.html) 17:57:12 INFO - PROCESS | 3935 | RSS: Main: 284688384 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | __start_tp_report 17:57:12 INFO - PROCESS | 3935 | _x_x_mozilla_page_load 17:57:12 INFO - PROCESS | 3935 | _x_x_mozilla_page_load_details 17:57:12 INFO - PROCESS | 3935 | |i|pagename|runs| 17:57:12 INFO - PROCESS | 3935 | |0;dojo.html;2678.12960787744;2700.2169370882443;2730.0444844304498;2744.341358074063;2761.0750398616474;4622.930656040154;4706.85879423617;4742.359781429506;4757.857264282073;4800.879978000552;4752.976235118828;4765.952340476594;4766.189747742884;4767.904641907168;4837.709737415757;4162.500499940012;4303.139372125572;4395.692301538894;4423.734575925439;4510.075434535912;4398.582134697199;4465.040016396474;4474.530174331697;4479.238529450001;4498.122866041123;4257.127288905783;4296.8281268749215;4333.804978775963;4363.214621368153;4375.1031038637;4333.761643109628;4336.479622445295;4345.543717909605;4369.235383807821;4394.824207031715;4350.477942646871;4370.956290437087;4377.190219809346;4377.430933978594;4418.64650827933;4524.185646583614;4618.468876079261;4656.674032817704;4665.2768820832825;4682.695624784378;5083.237514372837;5106.05537975472;5147.22791581262;5171.4311425743135;5171.741412929338;5113.667611605234;5166.586673066184;5167.689938603696;5189.481051894818;5199.194124910645;4674.509176536482;4737.739424331626;4768.809247630125;4774.761261936889;4804.0872234275375;5260.500252476008;5288;5289.867753306121;5293.814716484904;5296.311479507639;4920.286558449005;5027.84916452507;5064.442911279756;5068.214426763784;5072.82245121419;5111.974440127776;5150.072986862401;5153.123968925292;5161.509656582619;5187.455317191716;5124.5131712487255;5128.282040514331;5129.538341549279;5141.408737995103;5186.299849520342;4910.459849416561;4953.281774142729;5092.745362731848;5115.104856650071;5117.923231151536;4257.127288905767;4291.29193683045;4292.163028209469;4310.547392523803;4336.804843782037;3748.5689145748042;3766.3785475396253;3768.2651882883124;3802.4106263529197;3804.0489877530617;4340.2187606230555;4451.443569553806;4464.218761716752;4465.620422264079;4510.8421205257655;5105.923411148836;5121.539061484485;5135.229715542699;5138.691678499302;5143.151380022329;4441.289393697058;4450.109978004412;4455.26488129455;4465.486469056035;4498.955010449919;4505.7296562206375;4536.364908912755;4589.0363023765385;4605.884852878705;4631.235846085358;4823.107725070873;4828.7102773833685;4845.612351011981;4850.1269771441475;4890.559849613552;5127.950655756462;5139.203423469368;5142.074426603247;5142.280080788767;5194.68831870089;4716.740579268036;4826.372571565674;4849.95150048495;4887.560119589112;4892.241702536113;5111.182210846247;5137.666051706777;5137.743112844418;5142.04872098663;5174.8965020700125;5194.558462530727;5204.765785539736;5208.24480450325;5211.921821172609;5249.343832020997;4906.9018619626695;4931.5315045070665;4970.776315065758;4997.475265097112;5021.995600879912;4998.1503144464805;5001.174806156943;5015.322931404214;5023.748812559431;5071.92392114126;4932.4081110267;4954.380702412199;4955.454899961073;5002.749862506933;5003.899922001467;4897.706137631753;4974.825881094144;4976.850694479172;5018.974905125451;5048.899022019613;4839.201531747222;4875.80496780139;4898.461169271449;4922.975385123052;4957.578605818546 17:57:12 INFO - PROCESS | 3935 | |1;ext.html;551.5670198893866;602.7829981206762;604.8306474187226;616.4759954039067;621.3196549777992;42251.36622950658;43570.564294357006;43584.78207608958;43612.56387436124;46348.76825615876;43261.13477730443;43756.90607734808;43969.7801510992;44100;44120.11759764803;7532.7740167795055;7916.604169791502;8087.878681819778;10012.249081318894;10286.537105830255;10157.492125393737;10302.33034852736;10311.123554497877;10313;10319.968003199658;10294.124999375024;10329.58681652733;10366;10376;10378;7622.733204337849;10219.38683678978;10433.478326083741;10454.477276136162;10462.058414742634;9895.950520247352;9958.25313101513;9991.200703943667;10293.433861137635;10303.587856485732;40454.393184101966;40754.79622601868;40790;40798.592014079775;40815.387769183486;3224.8226347550876;3287.474004159347;3325.5011748237716;3368.747343949189;3725.1618385863235;30663;30999.225019374695;31437.37125257482;31446;31838.522422163685;3493.4975227474933;3522.806245656488;3547.9113022174392;3568.750187486877;3613.8012409317475;277461.61269193725;277697;278451;278874;280205;34659.4801077984;61231.01998370092;61802.38197617966;61868.69065654643;61876.38123618796;34692.47961280583;36417.45373819395;36595.26809463849;36645;36655;12012.519499219934;12927.676808079685;13058.412371443308;13209.669758256121;13214.27321497327;11222;11436.428178591037;11440.313581185155;11693.766124677459;11751.353675547927;8417.663293468193;8471.364647651451;8499.192576705203;8571.442856214326;8578.056413794478;3281.1797050737314;3301.273719781644;3324.5511855899626;3358.647342029101;3374.763766536368;946.2902822882838;991.6628346362271;1053.7732845088447;1081.3944191252767;1095.884932082136;28858;29120.563191552144;29131.27171820679;29241.707582924322;29339.85330073336;975.1223898491272;999.6401295533602;1005.2209537608412;1049.800537897797;1060.4485667452882;14793.92603036978;14808.555743327717;14952.252387380806;15192.924035379752;15297.158656273788;431315;434423;436447;440290;442506;51889.2216616751;52305.7384713074;52664.21003684948;52787.472125279026;52833.207501887504;1156.919015668912;1177.1936223686973;1235.932023738703;1257.126297223421;1298.6623477895625;27397.58903616487;29672.258193545335;30066.699333007266;30440.84779576088;30479.238019048815;42791.57208428001;43043;43325.78337108294;43339.78330108456;43355;318526;320128;321381;322031;322668;22679.659805102605;24588;25873.09444169445;27297.72702272952;28740.712592873802;43703;44118.33822492729;44488.55511444844;45614.31578526391;45639.08721825478;37368.81315593405;37595.2480950374;37840.62159378481;37916.810415947744;38065.61934380732;38105.428418574294;38228.42657360197;38747.6125238744;38815.80592097021;38896;36034.4594831083;36194.819025904704;36398.63601363952;36599;37446.438303424926;33742;34591.4811277826;34617.30765384729;34924.65075349214;35366;26206.213813585622;26390;26399.008525481524;26510.469790603693;26913.596296055184 17:57:12 INFO - PROCESS | 3935 | |2;jquery.html;359.53231086173855;372.96456836600515;382.55814534212976;387.44982125381955;405.88229413470077;16109.113998730065;16706.081165535907;17497.56256093598;17503.73744393832;17915;11303.434828258574;11404.828927566094;11541.884581154185;11542.884571154285;11582;4265.890868374214;4524.592786649209;5221.503957124067;5250.6324557281005;5257.290265814105;5120.436751957282;5211.973940130294;5226.503482169207;5233.842984710464;5257.132573125449;4651.88370290742;4767.90464190716;4791.976040119795;4854.12625727369;5144.0740666679985;4661.46393164787;4721.622270218374;4725.929111063337;4731.763411829412;4732.408448943899;4488.820447182109;4593.315734422446;4626.421697287839;4641.048585040058;4643.605293550052;11803.586874459354;12284.324362160078;12295.016398688083;12352.876471235262;12356.691082722913;2039.2964427272568;2056.992073883801;2117.512972016445;2385.9284221473386;2580.096966061882;8639.481631102153;8949.463032218087;9158.908410915872;9291.303152263607;9320.627174912996;1776.5292197567655;1793.9910300448544;1814.6461440019202;1843.6220574782137;1844.5388652836791;51537.74231128858;56999.715001425146;68930.9999999995;69453.3054669452;71016.6449167756;9687.128158465772;9877.111060004634;10381.377117372982;10419.479026048819;10513.211509136845;12130.575429859913;14554.636134096521;14585.708285834437;14595.343209555596;14609.707805843824;6871.690773915186;7114.359707626235;7242.203357630657;7247.057882475245;7360.300771426706;6770.458363330922;6809.455243580502;6809.795706128725;6910.239873613898;6984.511084224056;4501.617362524222;4570.405847239837;4589.35748995141;4653.976730116396;4677.742724150205;1752.7546143539914;1845.5570663040774;1888.291890541047;1905.018915258643;1908.2462427341122;830.5930094253772;915.555955361649;917.7109210598641;919.5172534419349;959.9440615323228;16311.673766524604;16678.83321166797;16822.495325140266;17133.14334283281;17769.75611707194;870.9390342675952;911.7720569857536;920.5719340506696;973.425678849468;997.2420960070253;9610.615575377047;9682.273829462818;9708.854367184498;9859.457729824928;9882.654107106217;412923;418329;418405;422773;424028;82886.58556707298;83874.58062709647;85331;85680;85903;1002.6941782756184;1006.9697909062739;1009.1371877045168;1032.143321043532;1032.3599368391644;15127.848721512644;15350.846491534941;15487.84512154864;15582.9220853895;15795.921020394824;34886.82556587201;35120.29759404849;35428.64571354254;36009.81995090008;37141;47911.520884790705;50512.24231636602;52426;52831;52876.20685689641;7819.178986206594;7908.4068694846965;7920.881186782318;7941.126476087741;8097.704367301203;11024.173187011167;11779.764404711686;11886.465109069939;11931.46308416106;12159.270443773403;9540.045995400682;9996.450195239331;10047.598096076075;10177.592896284363;10435.634752783617;10152;10155.54300056514;10214.642487512901;10601.946990265;10604.840927385942;10332.535035923553;10393.636222732168;10414.583416663248;10893.237473376788;11220.38287894141;7772.650230739518;7943.841123177389;8073.475224110414;8118.797030074059;8205.712800052208;10056.094951454405;10169.440680762333;10320;10336.948315258376;10471 17:57:12 INFO - PROCESS | 3935 | |3;mootools.html;1306.5949555637758;1494.2155368431572;1533.3253368517853;1694.5085925081728;1866.132248504446;7032.367086962173;7864.646090925908;8080;8097.230763077506;8306.46008009479;8017.358611311096;8158.714444994426;8164.306033987104;8180.059293181286;8189.222023907734;2238.54109907469;2276.681264622954;2278.1229226747737;2278.1798552521163;2278.851874628144;2243.2036626997424;2263.9320820375415;2290.1755368067484;2291.301153148289;2292.289390289016;2203.162798136706;2224.5773303072447;2226.9978509670714;2273.374821923976;2287.0623044551735;2229.7770222977733;2234.3743751749453;2240.798328150458;2242.136777340719;2242.2488466363625;2174.2716190076344;2183.759786423492;2187.0814258011674;2187.803097721197;2188.627933251351;7907.881381779278;8087.353011759045;8144.267015968591;8149.062857771373;8153.633086511121;4729.290606409032;4839.854804355875;4842.6368022398465;4846.345743324641;4860;3123.2348074721613;3163;3209.3420848726187;3267.509873518967;3280.753943454227;1027.7327894747345;1039.4335087377397;1068.4550879051662;1076.2735153771173;1077.1329080090525;10972.067374273196;11099.611513596985;11104.55581776728;11107.333559986426;11109.666709998713;4332.631726303236;4345.9348109778375;4383.057642606855;4418.867433976986;4435.778211089432;8845.336599755045;8855.867161992575;8857.64569417216;8858.247049000778;8864.157904999143;4693.084848454587;4768.451628062748;4769.403824521934;4793.688410253322;4806.663533552618;3778.848846046127;4230.027093768451;4370.628496577763;4374.4531933508315;4436.201483732894;3586.8565257389932;3592.98203508986;3651.835667394974;3703.055720791181;3707.1844194277214;1079.2822772855964;1081.561967403203;1082.3884505254507;1087.4182312462954;1100.8678958524868;613.6072913335469;777.366446346226;834.6369329341755;840.23538579892;916.9403988740711;5413.296271484761;5724.398938111522;5769.884602308014;5818.4763371296785;5893.14549390336;913.2055112052424;918.3433844801;934.7663084228943;958.8609651600478;989.1690979577189;3665.3585622515952;3806.06751345922;3892.844286228519;3947.3289540778137;3988.581198974124;9337.579808908615;9651.131398174199;9826.164775993977;9927.702168934944;9980.750481237881;9884.80230395388;9907.3560218587;9910.603575856885;9923.305368624127;9931.900680993098;7528.736494222676;7707.11368192665;7728.574928378881;7729.768106956801;7734.961325193338;5546.473085056913;5559.416261292506;5633.943660563342;5745.454181852718;6007.098935159761;9144.954275228582;9359.485228312507;9401.952990235006;9446.811063778823;9486.430814151174;9328.95335523318;9493.430394176372;9635.13283804461;9645.614175432904;9659.420434773936;7709.306162445407;7869.370450364072;7872;7884.645190966306;7896.486728362638;7743.341815945708;7750.99237099196;7786.571738554434;7802.414818888719;7900.170482099526;7046.894296585453;7092.9645351772915;7131.607761573163;7138.964305178441;7296.015037969806;6843.62360070181;6927.549709268881;7000.159980802337;7144.3927266182955;7326.743563975236;6359.49124070064;6546.738130474728;6609.074729537965;6610.504212184009;6612.768553100618;4713.29300604912;4835.588974937029;4844.563989240985;4871.537203965618;4967.329410529533;4753.9762301188275;4857.757112144308;4891.1685013548495;4940.8764780880765;4961.776720047678 17:57:12 INFO - PROCESS | 3935 | |4;prototype.html;313.82269018004826;314.44500456693936;316.76875880607156;320.75943042717955;322.540379958559;4751.334813126161;4782.852115492283;4791.209450440678;4791.640626952975;4792.976035119828;4735.952640473594;4736.218523943545;4744.5766270119;4761.714297142174;4767.259950118208;3552.8578856845697;3649.908752281188;3721.2371463849845;3771.7736935783805;3791.981040094796;3691.926161476769;3754.3617585010616;3769.8492060317553;3777.6977841772728;3804.0870191154063;3700.0379901225606;3716.758410703296;3749.5065781255435;3757.3988161894104;3774.1508160663907;3573.2317551726373;3665.102049997741;3772.453294149386;3774.358359078963;3777.112378591029;3341.7827841190488;3351.5307856900054;3375.358681850465;3375.4093033719;3392.3045775615938;4581.404417425713;4592.311153326994;4597.195490789132;4597.230066424418;4638.536146385368;4181.456410666624;4304.870853874358;4307.310830267143;4328;4359.498657654379;4925.704457732511;4971.527704868032;4971.6938236072965;5007.774650140753;5009;3672.192117734094;3673.5591728992426;3678.7608805427562;3702.333579955607;3744.9063773405883;5104.617153713486;5231.764570594294;5290.338707661543;5295.1202323175485;5299.682019078868;3781.470594116826;3790.8157147995266;3806.600306967784;3812.7140464465274;3816.8664096757034;3796.145867179918;3814.1990182062077;3821.5032045833864;3864.9033774155873;3866.903327416837;4276.978615106905;4290.249206388869;4299.333603291495;4308.46144231971;4351.97824010878;4792.102218271541;5014.448410674823;5015.548600625962;5025.723585202849;5047.955577990846;1328.455333313337;1331.780256257726;1339.9732005359838;1344.536135033412;1344.7781116115927;862.6592495964185;881.3962435731545;891.5185799668236;918.4810582021137;940.9341346105707;4429.9778501107285;4446.977765111218;4450.1989641864775;4457.3701516105;4458.866234012985;5072.721000345016;5076.847694569169;5099.87250318745;5102.719350435687;5103;4274.615284624337;4428.535003824616;4440.911181776346;4446.9777651111535;4450.2434586120435;2749.340158361979;2753.6144939708456;2771.725006497034;2773.8751756171023;2778.402418609308;4935.827246046371;4938.481459446778;4940.258961155855;4945.208766597327;4972.627052971042;5104.642675012714;5325.520703136736;5334.226537152169;5341.359036915595;5353.625246232804;4936.777844996912;4957.702537847741;4964.67729597575;4966.950330496721;4974;5230.424653288212;5245.7114858683135;5250.789968401221;5253.422123566481;5296.602754793483;4875.219964805614;5128.666636668754;5140.5349475399335;5150.8969820604125;5152.690838549699;4913.140200464976;4941.319951216604;4944.428896973482;4946.826861059845;4952.653314267966;3717.665410113103;3738.5513738351574;3745.4943582615997;3747.250549889978;3759.755615884959;5032.597392208688;5120.871978200575;5124.513890971576;5194.246834208943;5201.817936372209;4997.675151115166;5002.799888004439;5030.974845125751;5032.169692000779;5054.721990290423;5029.547340739205;5032.3961124665275;5051.595872330278;5055.042799962063;5069.8732531687;4673.130747700947;4932.630052745986;4939.456659767495;4956.677815941952;4963.652544322007;4816.735079570657;4881.584340541203;5058.1654027085115;5066.493350664904;5080.517350851663;4966.726830024383;4989.675671081513;4992.775325110306;5001.699898006132;5005.34930459038 17:57:12 INFO - PROCESS | 3935 | |5;yui.html;692.5498426023084;709.1029847243235;718.5939943931678;732.9560226386417;747.5290566942825;12024;12634.62096137115;12640.241585504864;12670.873291267097;12703.174293670929;12493.312867792269;12529.185602935802;12530.812037819418;12533.2480051197;12549.247045177273;663.5786275714908;827.5448503323179;859.6045818923303;862.2455351567379;863.0851297624507;843.0852525010371;844.5988155626089;846.9703560375389;848.7284069097891;848.8132610825609;704.7498138160956;906.623751143278;908.8182363527288;909.0772865541447;909.236241557092;883.1168831168832;903.3405613901823;904.0823564082461;905.1446383167909;907.2560500389683;667.5293917787997;667.8096742428434;667.8831204539187;668.2215219269546;668.224859163373;12201.572944946884;12220.877791222063;12222.144449888423;12233.388330583435;12257.325847078408;12130;12190.939045304805;12205.328706921116;12364.752704945851;12594.273344289502;9981;10029.1475224606;10083.344582602107;11009.669709908716;11169.106471482262;4251.681123915688;4293.162833247518;4298.097399546099;4301.075268817188;4461.620762235214;46071.53928460706;46634.76682616565;46941.29588056182;47278.76360618175;47359.5264047362;10006.69979900604;10054.195664346835;10080.243981701255;10160.390376577428;10166.542505587266;17692.911535442498;17886.837355571846;17912.28350865977;17915.283388664568;17925.282988680567;7032.05067315916;7080.256573059857;7221.74723884672;7254.5647261164495;7483.13943896448;3823.847046118124;3835.4438606401914;3843.3466310727244;3851.2105018471148;3852.3451013327804;2897.884084636591;2980.538016607429;2982.8657710403086;2985.2536865783554;2987.0889378739694;475.7835184990821;503.25518233015345;542.5415523882314;563.7012383436785;578.9247397838254;597.6742675242003;602.9035354343284;607.9361667024987;618.1870839845589;626.2922897126309;8569.528675922884;8768.386212965032;8797.51613661242;8976.775580610538;9156.084391560924;609.8993666045053;620.9906851397233;624.500399680254;625.8341539492038;634.0647544871371;3537.1864471171775;4487.214737420938;4730.314104454835;4741.786619602126;4801.807927682854;58949;61317.693411533546;61599;62196.68901655463;63380.36619633837;51742.22386664203;52878.735606322494;53477.732611336694;53699;56780.43219567752;50181;50558;50891.236631450556;51382.743086285074;51938.74030629823;8656.48061116335;8851.822963540822;8879.91120088791;8916.15296546827;9198.172164505228;33251.50122748112;33404.498932516515;33577.66422335745;33836.32327353489;34484.82757586196;39467.80266098651;40373;42087.158256835304;42874.57125428706;42914.35628465513;11355.943220283845;11470.139739519402;11551.826722599335;11648.94175529117;12056.09579281575;11662.88337116618;12020.459079341626;12115.515379384726;12228.816567751668;12915.870841291468;11549.595764148215;12103.75792484163;12121.515139394678;12122.743396151873;12238.204516706386;11253.606123785628;11535.653930382103;11623.476943537731;12101.878981210428;12111.939440303095;11707.765844683228;12199.146059776085;12228.449719762453;12231;12748.49006039748;8757.824843503222;9010.504422256576;9253.982061973313;9268;9490.751086281813;9258.814823703353;9606.087421694929;9822.852657210291;10208.336458130198;10256.81887222816 17:57:12 INFO - PROCESS | 3935 | __end_tp_report 17:57:12 INFO - PROCESS | 3935 | __start_cc_report 17:57:12 INFO - PROCESS | 3935 | _x_x_mozilla_cycle_collect,1214 17:57:12 INFO - PROCESS | 3935 | __end_cc_report 17:57:12 INFO - PROCESS | 3935 | __startTimestamp1463619432791__endTimestamp 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | ------- Summary: start ------- 17:57:12 INFO - PROCESS | 3935 | Number of tests: 6 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#0] dojo.html Cycles:165 Average:4731.99 Median:4850.13 stddev:501.93 (10.3%) stddev-sans-first:476.91 17:57:12 INFO - PROCESS | 3935 | Values: 2678.1 2700.2 2730.0 2744.3 2761.1 4622.9 4706.9 4742.4 4757.9 4800.9 4753.0 4766.0 4766.2 4767.9 4837.7 4162.5 4303.1 4395.7 4423.7 4510.1 4398.6 4465.0 4474.5 4479.2 4498.1 4257.1 4296.8 4333.8 4363.2 4375.1 4333.8 4336.5 4345.5 4369.2 4394.8 4350.5 4371.0 4377.2 4377.4 4418.6 4524.2 4618.5 4656.7 4665.3 4682.7 5083.2 5106.1 5147.2 5171.4 5171.7 5113.7 5166.6 5167.7 5189.5 5199.2 4674.5 4737.7 4768.8 4774.8 4804.1 5260.5 5288.0 5289.9 5293.8 5296.3 4920.3 5027.8 5064.4 5068.2 5072.8 5112.0 5150.1 5153.1 5161.5 5187.5 5124.5 5128.3 5129.5 5141.4 5186.3 4910.5 4953.3 5092.7 5115.1 5117.9 4257.1 4291.3 4292.2 4310.5 4336.8 3748.6 3766.4 3768.3 3802.4 3804.0 4340.2 4451.4 4464.2 4465.6 4510.8 5105.9 5121.5 5135.2 5138.7 5143.2 4441.3 4450.1 4455.3 4465.5 4499.0 4505.7 4536.4 4589.0 4605.9 4631.2 4823.1 4828.7 4845.6 4850.1 4890.6 5128.0 5139.2 5142.1 5142.3 5194.7 4716.7 4826.4 4850.0 4887.6 4892.2 5111.2 5137.7 5137.7 5142.0 5174.9 5194.6 5204.8 5208.2 5211.9 5249.3 4906.9 4931.5 4970.8 4997.5 5022.0 4998.2 5001.2 5015.3 5023.7 5071.9 4932.4 4954.4 4955.5 5002.7 5003.9 4897.7 4974.8 4976.9 5019.0 5048.9 4839.2 4875.8 4898.5 4923.0 4957.6 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#1] ext.html Cycles:180 Average:49972.38 Median:34638.39 stddev:92912.42 (268.2%) stddev-sans-first:93098.97 17:57:12 INFO - PROCESS | 3935 | Values: 551.6 602.8 604.8 616.5 621.3 42251.4 43570.6 43584.8 43612.6 46348.8 43261.1 43756.9 43969.8 44100.0 44120.1 7532.8 7916.6 8087.9 10012.2 10286.5 10157.5 10302.3 10311.1 10313.0 10320.0 10294.1 10329.6 10366.0 10376.0 10378.0 7622.7 10219.4 10433.5 10454.5 10462.1 9896.0 9958.3 9991.2 10293.4 10303.6 40454.4 40754.8 40790.0 40798.6 40815.4 3224.8 3287.5 3325.5 3368.7 3725.2 30663.0 30999.2 31437.4 31446.0 31838.5 3493.5 3522.8 3547.9 3568.8 3613.8 277461.6 277697.0 278451.0 278874.0 280205.0 34659.5 61231.0 61802.4 61868.7 61876.4 34692.5 36417.5 36595.3 36645.0 36655.0 12012.5 12927.7 13058.4 13209.7 13214.3 11222.0 11436.4 11440.3 11693.8 11751.4 8417.7 8471.4 8499.2 8571.4 8578.1 3281.2 3301.3 3324.6 3358.6 3374.8 946.3 991.7 1053.8 1081.4 1095.9 28858.0 29120.6 29131.3 29241.7 29339.9 975.1 999.6 1005.2 1049.8 1060.4 14793.9 14808.6 14952.3 15192.9 15297.2 431315.0 434423.0 436447.0 440290.0 442506.0 51889.2 52305.7 52664.2 52787.5 52833.2 1156.9 1177.2 1235.9 1257.1 1298.7 27397.6 29672.3 30066.7 30440.8 30479.2 42791.6 43043.0 43325.8 43339.8 43355.0 318526.0 320128.0 321381.0 322031.0 322668.0 22679.7 24588.0 25873.1 27297.7 28740.7 43703.0 44118.3 44488.6 45614.3 45639.1 37368.8 37595.2 37840.6 37916.8 38065.6 38105.4 38228.4 38747.6 38815.8 38896.0 36034.5 36194.8 36398.6 36599.0 37446.4 33742.0 34591.5 34617.3 34924.7 35366.0 26206.2 26390.0 26399.0 26510.5 26913.6 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#2] jquery.html Cycles:180 Average:24779.29 Median:214146.95 stddev:69202.04 (32.3%) stddev-sans-first:69371.88 17:57:12 INFO - PROCESS | 3935 | Values: 359.5 373.0 382.6 387.4 405.9 16109.1 16706.1 17497.6 17503.7 17915.0 11303.4 11404.8 11541.9 11542.9 11582.0 4265.9 4524.6 5221.5 5250.6 5257.3 5120.4 5212.0 5226.5 5233.8 5257.1 4651.9 4767.9 4792.0 4854.1 5144.1 4661.5 4721.6 4725.9 4731.8 4732.4 4488.8 4593.3 4626.4 4641.0 4643.6 11803.6 12284.3 12295.0 12352.9 12356.7 2039.3 2057.0 2117.5 2385.9 2580.1 8639.5 8949.5 9158.9 9291.3 9320.6 1776.5 1794.0 1814.6 1843.6 1844.5 51537.7 56999.7 68931.0 69453.3 71016.6 9687.1 9877.1 10381.4 10419.5 10513.2 12130.6 14554.6 14585.7 14595.3 14609.7 6871.7 7114.4 7242.2 7247.1 7360.3 6770.5 6809.5 6809.8 6910.2 6984.5 4501.6 4570.4 4589.4 4654.0 4677.7 1752.8 1845.6 1888.3 1905.0 1908.2 830.6 915.6 917.7 919.5 959.9 16311.7 16678.8 16822.5 17133.1 17769.8 870.9 911.8 920.6 973.4 997.2 9610.6 9682.3 9708.9 9859.5 9882.7 412923.0 418329.0 418405.0 422773.0 424028.0 82886.6 83874.6 85331.0 85680.0 85903.0 1002.7 1007.0 1009.1 1032.1 1032.4 15127.8 15350.8 15487.8 15582.9 15795.9 34886.8 35120.3 35428.6 36009.8 37141.0 47911.5 50512.2 52426.0 52831.0 52876.2 7819.2 7908.4 7920.9 7941.1 8097.7 11024.2 11779.8 11886.5 11931.5 12159.3 9540.0 9996.5 10047.6 10177.6 10435.6 10152.0 10155.5 10214.6 10601.9 10604.8 10332.5 10393.6 10414.6 10893.2 11220.4 7772.7 7943.8 8073.5 8118.8 8205.7 10056.1 10169.4 10320.0 10336.9 10471.0 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#3] mootools.html Cycles:180 Average:5373.24 Median:5479.88 stddev:2984.83 (54.5%) stddev-sans-first:2977.56 17:57:12 INFO - PROCESS | 3935 | Values: 1306.6 1494.2 1533.3 1694.5 1866.1 7032.4 7864.6 8080.0 8097.2 8306.5 8017.4 8158.7 8164.3 8180.1 8189.2 2238.5 2276.7 2278.1 2278.2 2278.9 2243.2 2263.9 2290.2 2291.3 2292.3 2203.2 2224.6 2227.0 2273.4 2287.1 2229.8 2234.4 2240.8 2242.1 2242.2 2174.3 2183.8 2187.1 2187.8 2188.6 7907.9 8087.4 8144.3 8149.1 8153.6 4729.3 4839.9 4842.6 4846.3 4860.0 3123.2 3163.0 3209.3 3267.5 3280.8 1027.7 1039.4 1068.5 1076.3 1077.1 10972.1 11099.6 11104.6 11107.3 11109.7 4332.6 4345.9 4383.1 4418.9 4435.8 8845.3 8855.9 8857.6 8858.2 8864.2 4693.1 4768.5 4769.4 4793.7 4806.7 3778.8 4230.0 4370.6 4374.5 4436.2 3586.9 3593.0 3651.8 3703.1 3707.2 1079.3 1081.6 1082.4 1087.4 1100.9 613.6 777.4 834.6 840.2 916.9 5413.3 5724.4 5769.9 5818.5 5893.1 913.2 918.3 934.8 958.9 989.2 3665.4 3806.1 3892.8 3947.3 3988.6 9337.6 9651.1 9826.2 9927.7 9980.8 9884.8 9907.4 9910.6 9923.3 9931.9 7528.7 7707.1 7728.6 7729.8 7735.0 5546.5 5559.4 5633.9 5745.5 6007.1 9145.0 9359.5 9402.0 9446.8 9486.4 9329.0 9493.4 9635.1 9645.6 9659.4 7709.3 7869.4 7872.0 7884.6 7896.5 7743.3 7751.0 7786.6 7802.4 7900.2 7046.9 7093.0 7131.6 7139.0 7296.0 6843.6 6927.5 7000.2 7144.4 7326.7 6359.5 6546.7 6609.1 6610.5 6612.8 4713.3 4835.6 4844.6 4871.5 4967.3 4754.0 4857.8 4891.2 4940.9 4961.8 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#4] prototype.html Cycles:180 Average:4197.33 Median:4764.49 stddev:1198.83 (25.2%) stddev-sans-first:1166.21 17:57:12 INFO - PROCESS | 3935 | Values: 313.8 314.4 316.8 320.8 322.5 4751.3 4782.9 4791.2 4791.6 4793.0 4736.0 4736.2 4744.6 4761.7 4767.3 3552.9 3649.9 3721.2 3771.8 3792.0 3691.9 3754.4 3769.8 3777.7 3804.1 3700.0 3716.8 3749.5 3757.4 3774.2 3573.2 3665.1 3772.5 3774.4 3777.1 3341.8 3351.5 3375.4 3375.4 3392.3 4581.4 4592.3 4597.2 4597.2 4638.5 4181.5 4304.9 4307.3 4328.0 4359.5 4925.7 4971.5 4971.7 5007.8 5009.0 3672.2 3673.6 3678.8 3702.3 3744.9 5104.6 5231.8 5290.3 5295.1 5299.7 3781.5 3790.8 3806.6 3812.7 3816.9 3796.1 3814.2 3821.5 3864.9 3866.9 4277.0 4290.2 4299.3 4308.5 4352.0 4792.1 5014.4 5015.5 5025.7 5048.0 1328.5 1331.8 1340.0 1344.5 1344.8 862.7 881.4 891.5 918.5 940.9 4430.0 4447.0 4450.2 4457.4 4458.9 5072.7 5076.8 5099.9 5102.7 5103.0 4274.6 4428.5 4440.9 4447.0 4450.2 2749.3 2753.6 2771.7 2773.9 2778.4 4935.8 4938.5 4940.3 4945.2 4972.6 5104.6 5325.5 5334.2 5341.4 5353.6 4936.8 4957.7 4964.7 4967.0 4974.0 5230.4 5245.7 5250.8 5253.4 5296.6 4875.2 5128.7 5140.5 5150.9 5152.7 4913.1 4941.3 4944.4 4946.8 4952.7 3717.7 3738.6 3745.5 3747.3 3759.8 5032.6 5120.9 5124.5 5194.2 5201.8 4997.7 5002.8 5031.0 5032.2 5054.7 5029.5 5032.4 5051.6 5055.0 5069.9 4673.1 4932.6 4939.5 4956.7 4963.7 4816.7 4881.6 5058.2 5066.5 5080.5 4966.7 4989.7 4992.8 5001.7 5005.3 17:57:12 INFO - PROCESS | 3935 | 17:57:12 INFO - PROCESS | 3935 | [#5] yui.html Cycles:180 Average:13950.44 Median:47110.03 stddev:16413.34 (34.8%) stddev-sans-first:16429.19 17:57:12 INFO - PROCESS | 3935 | Values: 692.5 709.1 718.6 733.0 747.5 12024.0 12634.6 12640.2 12670.9 12703.2 12493.3 12529.2 12530.8 12533.2 12549.2 663.6 827.5 859.6 862.2 863.1 843.1 844.6 847.0 848.7 848.8 704.7 906.6 908.8 909.1 909.2 883.1 903.3 904.1 905.1 907.3 667.5 667.8 667.9 668.2 668.2 12201.6 12220.9 12222.1 12233.4 12257.3 12130.0 12190.9 12205.3 12364.8 12594.3 9981.0 10029.1 10083.3 11009.7 11169.1 4251.7 4293.2 4298.1 4301.1 4461.6 46071.5 46634.8 46941.3 47278.8 47359.5 10006.7 10054.2 10080.2 10160.4 10166.5 17692.9 17886.8 17912.3 17915.3 17925.3 7032.1 7080.3 7221.7 7254.6 7483.1 3823.8 3835.4 3843.3 3851.2 3852.3 2897.9 2980.5 2982.9 2985.3 2987.1 475.8 503.3 542.5 563.7 578.9 597.7 602.9 607.9 618.2 626.3 8569.5 8768.4 8797.5 8976.8 9156.1 609.9 621.0 624.5 625.8 634.1 3537.2 4487.2 4730.3 4741.8 4801.8 58949.0 61317.7 61599.0 62196.7 63380.4 51742.2 52878.7 53477.7 53699.0 56780.4 50181.0 50558.0 50891.2 51382.7 51938.7 8656.5 8851.8 8879.9 8916.2 9198.2 33251.5 33404.5 33577.7 33836.3 34484.8 39467.8 40373.0 42087.2 42874.6 42914.4 11355.9 11470.1 11551.8 11648.9 12056.1 11662.9 12020.5 12115.5 12228.8 12915.9 11549.6 12103.8 12121.5 12122.7 12238.2 11253.6 11535.7 11623.5 12101.9 12111.9 11707.8 12199.1 12228.4 12231.0 12748.5 8757.8 9010.5 9254.0 9268.0 9490.8 9258.8 9606.1 9822.9 10208.3 10256.8 17:57:12 INFO - PROCESS | 3935 | -------- Summary: end -------- 17:57:12 INFO - PROCESS | 3935 | 17:57:13 INFO - TEST-INFO | 3935: exit 0 17:57:13 INFO - TEST-OK | dromaeo_css | took 1119191ms 17:57:13 INFO - TEST-START | kraken 17:57:13 INFO - operating with platform_type : linux_ 17:57:13 INFO - Initialising browser for kraken test... 17:57:13 INFO - TEST-INFO | started process 4117 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphGg9zn/profile http://localhost:56521/getInfo.html) 17:57:18 INFO - PROCESS | 4117 | __metrics Screen width/height:1600/1200 17:57:18 INFO - PROCESS | 4117 | colorDepth:24 17:57:18 INFO - PROCESS | 4117 | Browser inner width/height: 1024/697 17:57:18 INFO - PROCESS | 4117 | __metrics 17:57:19 INFO - PROCESS | 4117 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:57:19 ERROR - PROCESS | 4117 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:57:20 INFO - TEST-INFO | 4117: exit 0 17:57:20 INFO - Browser initialized. 17:57:20 INFO - Running cycle 1/1 for kraken test... 17:57:20 INFO - TEST-INFO | started process 4199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphGg9zn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:32 INFO - PROCESS | 4199 | RSS: Main: 154386432 17:57:32 INFO - PROCESS | 4199 | 17:57:34 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56521/tests/kraken/driver.html?testName=audio-beat-detection) 17:57:35 INFO - PROCESS | 4199 | RSS: Main: 210173952 17:57:35 INFO - PROCESS | 4199 | 17:57:38 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56521/tests/kraken/driver.html?testName=audio-dft) 17:57:39 INFO - PROCESS | 4199 | RSS: Main: 197992448 17:57:39 INFO - PROCESS | 4199 | 17:57:42 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56521/tests/kraken/driver.html?testName=audio-fft) 17:57:43 INFO - PROCESS | 4199 | RSS: Main: 267255808 17:57:43 INFO - PROCESS | 4199 | 17:57:45 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56521/tests/kraken/driver.html?testName=audio-oscillator) 17:57:46 INFO - PROCESS | 4199 | RSS: Main: 176164864 17:57:46 INFO - PROCESS | 4199 | 17:57:48 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56521/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:57:49 INFO - PROCESS | 4199 | RSS: Main: 179814400 17:57:49 INFO - PROCESS | 4199 | 17:57:52 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56521/tests/kraken/driver.html?testName=imaging-darkroom) 17:57:52 INFO - PROCESS | 4199 | RSS: Main: 215732224 17:57:52 INFO - PROCESS | 4199 | 17:57:56 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56521/tests/kraken/driver.html?testName=imaging-desaturate) 17:57:57 INFO - PROCESS | 4199 | RSS: Main: 214700032 17:57:57 INFO - PROCESS | 4199 | 17:58:00 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56521/tests/kraken/driver.html?testName=json-parse-financial) 17:58:00 INFO - PROCESS | 4199 | RSS: Main: 215220224 17:58:00 INFO - PROCESS | 4199 | 17:58:02 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56521/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:58:03 INFO - PROCESS | 4199 | RSS: Main: 201920512 17:58:03 INFO - PROCESS | 4199 | 17:58:05 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:58:05 INFO - PROCESS | 4199 | RSS: Main: 207036416 17:58:05 INFO - PROCESS | 4199 | 17:58:08 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:08 INFO - PROCESS | 4199 | RSS: Main: 216768512 17:58:08 INFO - PROCESS | 4199 | 17:58:11 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:12 INFO - PROCESS | 4199 | RSS: Main: 224825344 17:58:12 INFO - PROCESS | 4199 | 17:58:15 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:15 INFO - PROCESS | 4199 | RSS: Main: 221138944 17:58:15 INFO - PROCESS | 4199 | 17:58:17 INFO - PROCESS | 4199 | Cycle 1(1): loaded http://localhost:56521/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56521/tests/kraken/driver.html?testName=ai-astar) 17:58:18 INFO - PROCESS | 4199 | RSS: Main: 267296768 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | __start_tp_report 17:58:18 INFO - PROCESS | 4199 | _x_x_mozilla_page_load 17:58:18 INFO - PROCESS | 4199 | _x_x_mozilla_page_load_details 17:58:18 INFO - PROCESS | 4199 | |i|pagename|runs| 17:58:18 INFO - PROCESS | 4199 | |0;ai-astar;115;108;113;113;114;112;114;114;114;114 17:58:18 INFO - PROCESS | 4199 | |1;audio-beat-detection;159;129;152;215;129;161;155;137;195;129 17:58:18 INFO - PROCESS | 4199 | |2;audio-dft;161;161;162;162;162;167;162;162;161;161 17:58:18 INFO - PROCESS | 4199 | |3;audio-fft;76;76;78;155;77;80;76;75;152;75 17:58:18 INFO - PROCESS | 4199 | |4;audio-oscillator;93;94;147;94;94;94;118;92;93;129 17:58:18 INFO - PROCESS | 4199 | |5;imaging-gaussian-blur;117;116;116;116;116;117;117;117;117;117 17:58:18 INFO - PROCESS | 4199 | |6;imaging-darkroom;147;145;147;146;146;145;146;146;146;145 17:58:18 INFO - PROCESS | 4199 | |7;imaging-desaturate;94;95;95;94;95;94;94;94;95;95 17:58:18 INFO - PROCESS | 4199 | |8;json-parse-financial;63;64;64;63;63;64;65;126;62;62 17:58:18 INFO - PROCESS | 4199 | |9;json-stringify-tinderbox;65;65;66;65;66;64;65;65;66;65 17:58:18 INFO - PROCESS | 4199 | |10;stanford-crypto-aes;82;81;88;82;85;82;80;82;83;87 17:58:18 INFO - PROCESS | 4199 | |11;stanford-crypto-ccm;147;138;138;139;137;127;128;142;218;130 17:58:18 INFO - PROCESS | 4199 | |12;stanford-crypto-pbkdf2;169;169;172;173;179;168;165;169;167;167 17:58:18 INFO - PROCESS | 4199 | |13;stanford-crypto-sha256-iterative;67;65;74;65;66;65;66;65;66;65 17:58:18 INFO - PROCESS | 4199 | __end_tp_report 17:58:18 INFO - PROCESS | 4199 | __start_cc_report 17:58:18 INFO - PROCESS | 4199 | _x_x_mozilla_cycle_collect,2099 17:58:18 INFO - PROCESS | 4199 | __end_cc_report 17:58:18 INFO - PROCESS | 4199 | __startTimestamp1463619498013__endTimestamp 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | ------- Summary: start ------- 17:58:18 INFO - PROCESS | 4199 | Number of tests: 14 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:1.97 (1.7%) stddev-sans-first:1.96 17:58:18 INFO - PROCESS | 4199 | Values: 115.0 108.0 113.0 113.0 114.0 112.0 114.0 114.0 114.0 114.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#1] audio-beat-detection Cycles:10 Average:156.10 Median:157.00 stddev:29.06 (18.5%) stddev-sans-first:30.80 17:58:18 INFO - PROCESS | 4199 | Values: 159.0 129.0 152.0 215.0 129.0 161.0 155.0 137.0 195.0 129.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.79 (1.1%) stddev-sans-first:1.86 17:58:18 INFO - PROCESS | 4199 | Values: 161.0 161.0 162.0 162.0 162.0 167.0 162.0 162.0 161.0 161.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#3] audio-fft Cycles:10 Average:92.00 Median:76.00 stddev:32.46 (42.7%) stddev-sans-first:33.90 17:58:18 INFO - PROCESS | 4199 | Values: 76.0 76.0 78.0 155.0 77.0 80.0 76.0 75.0 152.0 75.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:93.50 stddev:19.58 (20.9%) stddev-sans-first:20.29 17:58:18 INFO - PROCESS | 4199 | Values: 93.0 94.0 147.0 94.0 94.0 94.0 118.0 92.0 93.0 129.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:58:18 INFO - PROCESS | 4199 | Values: 117.0 116.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 17:58:18 INFO - PROCESS | 4199 | Values: 147.0 145.0 147.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 17:58:18 INFO - PROCESS | 4199 | Values: 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#8] json-parse-financial Cycles:10 Average:69.60 Median:63.50 stddev:19.84 (31.2%) stddev-sans-first:20.90 17:58:18 INFO - PROCESS | 4199 | Values: 63.0 64.0 64.0 63.0 63.0 64.0 65.0 126.0 62.0 62.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 17:58:18 INFO - PROCESS | 4199 | Values: 65.0 65.0 66.0 65.0 66.0 64.0 65.0 65.0 66.0 65.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.50 stddev:2.62 (3.2%) stddev-sans-first:2.74 17:58:18 INFO - PROCESS | 4199 | Values: 82.0 81.0 88.0 82.0 85.0 82.0 80.0 82.0 83.0 87.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:138.50 stddev:26.61 (19.2%) stddev-sans-first:28.21 17:58:18 INFO - PROCESS | 4199 | Values: 147.0 138.0 138.0 139.0 137.0 127.0 128.0 142.0 218.0 130.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.00 stddev:3.99 (2.4%) stddev-sans-first:4.23 17:58:18 INFO - PROCESS | 4199 | Values: 169.0 169.0 172.0 173.0 179.0 168.0 165.0 169.0 167.0 167.0 17:58:18 INFO - PROCESS | 4199 | 17:58:18 INFO - PROCESS | 4199 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:2.76 (4.2%) stddev-sans-first:2.92 17:58:18 INFO - PROCESS | 4199 | Values: 67.0 65.0 74.0 65.0 66.0 65.0 66.0 65.0 66.0 65.0 17:58:18 INFO - PROCESS | 4199 | -------- Summary: end -------- 17:58:18 INFO - PROCESS | 4199 | 17:58:19 INFO - TEST-INFO | 4199: exit 0 17:58:19 INFO - TEST-OK | kraken | took 65530ms 17:58:19 INFO - SUITE-END | took 1184s 17:58:19 INFO - Completed test suite (00:19:44) 17:58:19 INFO - javascript benchmark 17:58:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2678.12960787744, 2700.2169370882443, 2730.0444844304498, 2744.341358074063, 2761.0750398616474, 4622.930656040154, 4706.85879423617, 4742.359781429506, 4757.857264282073, 4800.879978000552, 4752.976235118828, 4765.952340476594, 4766.189747742884, 4767.904641907168, 4837.709737415757, 4162.500499940012, 4303.139372125572, 4395.692301538894, 4423.734575925439, 4510.075434535912, 4398.582134697199, 4465.040016396474, 4474.530174331697, 4479.238529450001, 4498.122866041123, 4257.127288905783, 4296.8281268749215, 4333.804978775963, 4363.214621368153, 4375.1031038637, 4333.761643109628, 4336.479622445295, 4345.543717909605, 4369.235383807821, 4394.824207031715, 4350.477942646871, 4370.956290437087, 4377.190219809346, 4377.430933978594, 4418.64650827933, 4524.185646583614, 4618.468876079261, 4656.674032817704, 4665.2768820832825, 4682.695624784378, 5083.237514372837, 5106.05537975472, 5147.22791581262, 5171.4311425743135, 5171.741412929338, 5113.667611605234, 5166.586673066184, 5167.689938603696, 5189.481051894818, 5199.194124910645, 4674.509176536482, 4737.739424331626, 4768.809247630125, 4774.761261936889, 4804.0872234275375, 5260.500252476008, 5288.0, 5289.867753306121, 5293.814716484904, 5296.311479507639, 4920.286558449005, 5027.84916452507, 5064.442911279756, 5068.214426763784, 5072.82245121419, 5111.974440127776, 5150.072986862401, 5153.123968925292, 5161.509656582619, 5187.455317191716, 5124.5131712487255, 5128.282040514331, 5129.538341549279, 5141.408737995103, 5186.299849520342, 4910.459849416561, 4953.281774142729, 5092.745362731848, 5115.104856650071, 5117.923231151536, 4257.127288905767, 4291.29193683045, 4292.163028209469, 4310.547392523803, 4336.804843782037, 3748.5689145748042, 3766.3785475396253, 3768.2651882883124, 3802.4106263529197, 3804.0489877530617, 4340.2187606230555, 4451.443569553806, 4464.218761716752, 4465.620422264079, 4510.8421205257655, 5105.923411148836, 5121.539061484485, 5135.229715542699, 5138.691678499302, 5143.151380022329, 4441.289393697058, 4450.109978004412, 4455.26488129455, 4465.486469056035, 4498.955010449919, 4505.7296562206375, 4536.364908912755, 4589.0363023765385, 4605.884852878705, 4631.235846085358, 4823.107725070873, 4828.7102773833685, 4845.612351011981, 4850.1269771441475, 4890.559849613552, 5127.950655756462, 5139.203423469368, 5142.074426603247, 5142.280080788767, 5194.68831870089, 4716.740579268036, 4826.372571565674, 4849.95150048495, 4887.560119589112, 4892.241702536113, 5111.182210846247, 5137.666051706777, 5137.743112844418, 5142.04872098663, 5174.8965020700125, 5194.558462530727, 5204.765785539736, 5208.24480450325, 5211.921821172609, 5249.343832020997, 4906.9018619626695, 4931.5315045070665, 4970.776315065758, 4997.475265097112, 5021.995600879912, 4998.1503144464805, 5001.174806156943, 5015.322931404214, 5023.748812559431, 5071.92392114126, 4932.4081110267, 4954.380702412199, 4955.454899961073, 5002.749862506933, 5003.899922001467, 4897.706137631753, 4974.825881094144, 4976.850694479172, 5018.974905125451, 5048.899022019613, 4839.201531747222, 4875.80496780139, 4898.461169271449, 4922.975385123052, 4957.578605818546], "value": 4700.156242617287, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.5670198893866, 602.7829981206762, 604.8306474187226, 616.4759954039067, 621.3196549777992, 42251.36622950658, 43570.564294357006, 43584.78207608958, 43612.56387436124, 46348.76825615876, 43261.13477730443, 43756.90607734808, 43969.7801510992, 44100.0, 44120.11759764803, 7532.7740167795055, 7916.604169791502, 8087.878681819778, 10012.249081318894, 10286.537105830255, 10157.492125393737, 10302.33034852736, 10311.123554497877, 10313.0, 10319.968003199658, 10294.124999375024, 10329.58681652733, 10366.0, 10376.0, 10378.0, 7622.733204337849, 10219.38683678978, 10433.478326083741, 10454.477276136162, 10462.058414742634, 9895.950520247352, 9958.25313101513, 9991.200703943667, 10293.433861137635, 10303.587856485732, 40454.393184101966, 40754.79622601868, 40790.0, 40798.592014079775, 40815.387769183486, 3224.8226347550876, 3287.474004159347, 3325.5011748237716, 3368.747343949189, 3725.1618385863235, 30663.0, 30999.225019374695, 31437.37125257482, 31446.0, 31838.522422163685, 3493.4975227474933, 3522.806245656488, 3547.9113022174392, 3568.750187486877, 3613.8012409317475, 277461.61269193725, 277697.0, 278451.0, 278874.0, 280205.0, 34659.4801077984, 61231.01998370092, 61802.38197617966, 61868.69065654643, 61876.38123618796, 34692.47961280583, 36417.45373819395, 36595.26809463849, 36645.0, 36655.0, 12012.519499219934, 12927.676808079685, 13058.412371443308, 13209.669758256121, 13214.27321497327, 11222.0, 11436.428178591037, 11440.313581185155, 11693.766124677459, 11751.353675547927, 8417.663293468193, 8471.364647651451, 8499.192576705203, 8571.442856214326, 8578.056413794478, 3281.1797050737314, 3301.273719781644, 3324.5511855899626, 3358.647342029101, 3374.763766536368, 946.2902822882838, 991.6628346362271, 1053.7732845088447, 1081.3944191252767, 1095.884932082136, 28858.0, 29120.563191552144, 29131.27171820679, 29241.707582924322, 29339.85330073336, 975.1223898491272, 999.6401295533602, 1005.2209537608412, 1049.800537897797, 1060.4485667452882, 14793.92603036978, 14808.555743327717, 14952.252387380806, 15192.924035379752, 15297.158656273788, 431315.0, 434423.0, 436447.0, 440290.0, 442506.0, 51889.2216616751, 52305.7384713074, 52664.21003684948, 52787.472125279026, 52833.207501887504, 1156.919015668912, 1177.1936223686973, 1235.932023738703, 1257.126297223421, 1298.6623477895625, 27397.58903616487, 29672.258193545335, 30066.699333007266, 30440.84779576088, 30479.238019048815, 42791.57208428001, 43043.0, 43325.78337108294, 43339.78330108456, 43355.0, 318526.0, 320128.0, 321381.0, 322031.0, 322668.0, 22679.659805102605, 24588.0, 25873.09444169445, 27297.72702272952, 28740.712592873802, 43703.0, 44118.33822492729, 44488.55511444844, 45614.31578526391, 45639.08721825478, 37368.81315593405, 37595.2480950374, 37840.62159378481, 37916.810415947744, 38065.61934380732, 38105.428418574294, 38228.42657360197, 38747.6125238744, 38815.80592097021, 38896.0, 36034.4594831083, 36194.819025904704, 36398.63601363952, 36599.0, 37446.438303424926, 33742.0, 34591.4811277826, 34617.30765384729, 34924.65075349214, 35366.0, 26206.213813585622, 26390.0, 26399.008525481524, 26510.469790603693, 26913.596296055184], "value": 17860.80446917712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.53231086173855, 372.96456836600515, 382.55814534212976, 387.44982125381955, 405.88229413470077, 16109.113998730065, 16706.081165535907, 17497.56256093598, 17503.73744393832, 17915.0, 11303.434828258574, 11404.828927566094, 11541.884581154185, 11542.884571154285, 11582.0, 4265.890868374214, 4524.592786649209, 5221.503957124067, 5250.6324557281005, 5257.290265814105, 5120.436751957282, 5211.973940130294, 5226.503482169207, 5233.842984710464, 5257.132573125449, 4651.88370290742, 4767.90464190716, 4791.976040119795, 4854.12625727369, 5144.0740666679985, 4661.46393164787, 4721.622270218374, 4725.929111063337, 4731.763411829412, 4732.408448943899, 4488.820447182109, 4593.315734422446, 4626.421697287839, 4641.048585040058, 4643.605293550052, 11803.586874459354, 12284.324362160078, 12295.016398688083, 12352.876471235262, 12356.691082722913, 2039.2964427272568, 2056.992073883801, 2117.512972016445, 2385.9284221473386, 2580.096966061882, 8639.481631102153, 8949.463032218087, 9158.908410915872, 9291.303152263607, 9320.627174912996, 1776.5292197567655, 1793.9910300448544, 1814.6461440019202, 1843.6220574782137, 1844.5388652836791, 51537.74231128858, 56999.715001425146, 68930.9999999995, 69453.3054669452, 71016.6449167756, 9687.128158465772, 9877.111060004634, 10381.377117372982, 10419.479026048819, 10513.211509136845, 12130.575429859913, 14554.636134096521, 14585.708285834437, 14595.343209555596, 14609.707805843824, 6871.690773915186, 7114.359707626235, 7242.203357630657, 7247.057882475245, 7360.300771426706, 6770.458363330922, 6809.455243580502, 6809.795706128725, 6910.239873613898, 6984.511084224056, 4501.617362524222, 4570.405847239837, 4589.35748995141, 4653.976730116396, 4677.742724150205, 1752.7546143539914, 1845.5570663040774, 1888.291890541047, 1905.018915258643, 1908.2462427341122, 830.5930094253772, 915.555955361649, 917.7109210598641, 919.5172534419349, 959.9440615323228, 16311.673766524604, 16678.83321166797, 16822.495325140266, 17133.14334283281, 17769.75611707194, 870.9390342675952, 911.7720569857536, 920.5719340506696, 973.425678849468, 997.2420960070253, 9610.615575377047, 9682.273829462818, 9708.854367184498, 9859.457729824928, 9882.654107106217, 412923.0, 418329.0, 418405.0, 422773.0, 424028.0, 82886.58556707298, 83874.58062709647, 85331.0, 85680.0, 85903.0, 1002.6941782756184, 1006.9697909062739, 1009.1371877045168, 1032.143321043532, 1032.3599368391644, 15127.848721512644, 15350.846491534941, 15487.84512154864, 15582.9220853895, 15795.921020394824, 34886.82556587201, 35120.29759404849, 35428.64571354254, 36009.81995090008, 37141.0, 47911.520884790705, 50512.24231636602, 52426.0, 52831.0, 52876.20685689641, 7819.178986206594, 7908.4068694846965, 7920.881186782318, 7941.126476087741, 8097.704367301203, 11024.173187011167, 11779.764404711686, 11886.465109069939, 11931.46308416106, 12159.270443773403, 9540.045995400682, 9996.450195239331, 10047.598096076075, 10177.592896284363, 10435.634752783617, 10152.0, 10155.54300056514, 10214.642487512901, 10601.946990265, 10604.840927385942, 10332.535035923553, 10393.636222732168, 10414.583416663248, 10893.237473376788, 11220.38287894141, 7772.650230739518, 7943.841123177389, 8073.475224110414, 8118.797030074059, 8205.712800052208, 10056.094951454405, 10169.440680762333, 10320.0, 10336.948315258376, 10471.0], "value": 8061.195630243066, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.5949555637758, 1494.2155368431572, 1533.3253368517853, 1694.5085925081728, 1866.132248504446, 7032.367086962173, 7864.646090925908, 8080.0, 8097.230763077506, 8306.46008009479, 8017.358611311096, 8158.714444994426, 8164.306033987104, 8180.059293181286, 8189.222023907734, 2238.54109907469, 2276.681264622954, 2278.1229226747737, 2278.1798552521163, 2278.851874628144, 2243.2036626997424, 2263.9320820375415, 2290.1755368067484, 2291.301153148289, 2292.289390289016, 2203.162798136706, 2224.5773303072447, 2226.9978509670714, 2273.374821923976, 2287.0623044551735, 2229.7770222977733, 2234.3743751749453, 2240.798328150458, 2242.136777340719, 2242.2488466363625, 2174.2716190076344, 2183.759786423492, 2187.0814258011674, 2187.803097721197, 2188.627933251351, 7907.881381779278, 8087.353011759045, 8144.267015968591, 8149.062857771373, 8153.633086511121, 4729.290606409032, 4839.854804355875, 4842.6368022398465, 4846.345743324641, 4860.0, 3123.2348074721613, 3163.0, 3209.3420848726187, 3267.509873518967, 3280.753943454227, 1027.7327894747345, 1039.4335087377397, 1068.4550879051662, 1076.2735153771173, 1077.1329080090525, 10972.067374273196, 11099.611513596985, 11104.55581776728, 11107.333559986426, 11109.666709998713, 4332.631726303236, 4345.9348109778375, 4383.057642606855, 4418.867433976986, 4435.778211089432, 8845.336599755045, 8855.867161992575, 8857.64569417216, 8858.247049000778, 8864.157904999143, 4693.084848454587, 4768.451628062748, 4769.403824521934, 4793.688410253322, 4806.663533552618, 3778.848846046127, 4230.027093768451, 4370.628496577763, 4374.4531933508315, 4436.201483732894, 3586.8565257389932, 3592.98203508986, 3651.835667394974, 3703.055720791181, 3707.1844194277214, 1079.2822772855964, 1081.561967403203, 1082.3884505254507, 1087.4182312462954, 1100.8678958524868, 613.6072913335469, 777.366446346226, 834.6369329341755, 840.23538579892, 916.9403988740711, 5413.296271484761, 5724.398938111522, 5769.884602308014, 5818.4763371296785, 5893.14549390336, 913.2055112052424, 918.3433844801, 934.7663084228943, 958.8609651600478, 989.1690979577189, 3665.3585622515952, 3806.06751345922, 3892.844286228519, 3947.3289540778137, 3988.581198974124, 9337.579808908615, 9651.131398174199, 9826.164775993977, 9927.702168934944, 9980.750481237881, 9884.80230395388, 9907.3560218587, 9910.603575856885, 9923.305368624127, 9931.900680993098, 7528.736494222676, 7707.11368192665, 7728.574928378881, 7729.768106956801, 7734.961325193338, 5546.473085056913, 5559.416261292506, 5633.943660563342, 5745.454181852718, 6007.098935159761, 9144.954275228582, 9359.485228312507, 9401.952990235006, 9446.811063778823, 9486.430814151174, 9328.95335523318, 9493.430394176372, 9635.13283804461, 9645.614175432904, 9659.420434773936, 7709.306162445407, 7869.370450364072, 7872.0, 7884.645190966306, 7896.486728362638, 7743.341815945708, 7750.99237099196, 7786.571738554434, 7802.414818888719, 7900.170482099526, 7046.894296585453, 7092.9645351772915, 7131.607761573163, 7138.964305178441, 7296.015037969806, 6843.62360070181, 6927.549709268881, 7000.159980802337, 7144.3927266182955, 7326.743563975236, 6359.49124070064, 6546.738130474728, 6609.074729537965, 6610.504212184009, 6612.768553100618, 4713.29300604912, 4835.588974937029, 4844.563989240985, 4871.537203965618, 4967.329410529533, 4753.9762301188275, 4857.757112144308, 4891.1685013548495, 4940.8764780880765, 4961.776720047678], "value": 4324.9768029445695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.82269018004826, 314.44500456693936, 316.76875880607156, 320.75943042717955, 322.540379958559, 4751.334813126161, 4782.852115492283, 4791.209450440678, 4791.640626952975, 4792.976035119828, 4735.952640473594, 4736.218523943545, 4744.5766270119, 4761.714297142174, 4767.259950118208, 3552.8578856845697, 3649.908752281188, 3721.2371463849845, 3771.7736935783805, 3791.981040094796, 3691.926161476769, 3754.3617585010616, 3769.8492060317553, 3777.6977841772728, 3804.0870191154063, 3700.0379901225606, 3716.758410703296, 3749.5065781255435, 3757.3988161894104, 3774.1508160663907, 3573.2317551726373, 3665.102049997741, 3772.453294149386, 3774.358359078963, 3777.112378591029, 3341.7827841190488, 3351.5307856900054, 3375.358681850465, 3375.4093033719, 3392.3045775615938, 4581.404417425713, 4592.311153326994, 4597.195490789132, 4597.230066424418, 4638.536146385368, 4181.456410666624, 4304.870853874358, 4307.310830267143, 4328.0, 4359.498657654379, 4925.704457732511, 4971.527704868032, 4971.6938236072965, 5007.774650140753, 5009.0, 3672.192117734094, 3673.5591728992426, 3678.7608805427562, 3702.333579955607, 3744.9063773405883, 5104.617153713486, 5231.764570594294, 5290.338707661543, 5295.1202323175485, 5299.682019078868, 3781.470594116826, 3790.8157147995266, 3806.600306967784, 3812.7140464465274, 3816.8664096757034, 3796.145867179918, 3814.1990182062077, 3821.5032045833864, 3864.9033774155873, 3866.903327416837, 4276.978615106905, 4290.249206388869, 4299.333603291495, 4308.46144231971, 4351.97824010878, 4792.102218271541, 5014.448410674823, 5015.548600625962, 5025.723585202849, 5047.955577990846, 1328.455333313337, 1331.780256257726, 1339.9732005359838, 1344.536135033412, 1344.7781116115927, 862.6592495964185, 881.3962435731545, 891.5185799668236, 918.4810582021137, 940.9341346105707, 4429.9778501107285, 4446.977765111218, 4450.1989641864775, 4457.3701516105, 4458.866234012985, 5072.721000345016, 5076.847694569169, 5099.87250318745, 5102.719350435687, 5103.0, 4274.615284624337, 4428.535003824616, 4440.911181776346, 4446.9777651111535, 4450.2434586120435, 2749.340158361979, 2753.6144939708456, 2771.725006497034, 2773.8751756171023, 2778.402418609308, 4935.827246046371, 4938.481459446778, 4940.258961155855, 4945.208766597327, 4972.627052971042, 5104.642675012714, 5325.520703136736, 5334.226537152169, 5341.359036915595, 5353.625246232804, 4936.777844996912, 4957.702537847741, 4964.67729597575, 4966.950330496721, 4974.0, 5230.424653288212, 5245.7114858683135, 5250.789968401221, 5253.422123566481, 5296.602754793483, 4875.219964805614, 5128.666636668754, 5140.5349475399335, 5150.8969820604125, 5152.690838549699, 4913.140200464976, 4941.319951216604, 4944.428896973482, 4946.826861059845, 4952.653314267966, 3717.665410113103, 3738.5513738351574, 3745.4943582615997, 3747.250549889978, 3759.755615884959, 5032.597392208688, 5120.871978200575, 5124.513890971576, 5194.246834208943, 5201.817936372209, 4997.675151115166, 5002.799888004439, 5030.974845125751, 5032.169692000779, 5054.721990290423, 5029.547340739205, 5032.3961124665275, 5051.595872330278, 5055.042799962063, 5069.8732531687, 4673.130747700947, 4932.630052745986, 4939.456659767495, 4956.677815941952, 4963.652544322007, 4816.735079570657, 4881.584340541203, 5058.1654027085115, 5066.493350664904, 5080.517350851663, 4966.726830024383, 4989.675671081513, 4992.775325110306, 5001.699898006132, 5005.34930459038], "value": 3825.2150539605577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.5498426023084, 709.1029847243235, 718.5939943931678, 732.9560226386417, 747.5290566942825, 12024.0, 12634.62096137115, 12640.241585504864, 12670.873291267097, 12703.174293670929, 12493.312867792269, 12529.185602935802, 12530.812037819418, 12533.2480051197, 12549.247045177273, 663.5786275714908, 827.5448503323179, 859.6045818923303, 862.2455351567379, 863.0851297624507, 843.0852525010371, 844.5988155626089, 846.9703560375389, 848.7284069097891, 848.8132610825609, 704.7498138160956, 906.623751143278, 908.8182363527288, 909.0772865541447, 909.236241557092, 883.1168831168832, 903.3405613901823, 904.0823564082461, 905.1446383167909, 907.2560500389683, 667.5293917787997, 667.8096742428434, 667.8831204539187, 668.2215219269546, 668.224859163373, 12201.572944946884, 12220.877791222063, 12222.144449888423, 12233.388330583435, 12257.325847078408, 12130.0, 12190.939045304805, 12205.328706921116, 12364.752704945851, 12594.273344289502, 9981.0, 10029.1475224606, 10083.344582602107, 11009.669709908716, 11169.106471482262, 4251.681123915688, 4293.162833247518, 4298.097399546099, 4301.075268817188, 4461.620762235214, 46071.53928460706, 46634.76682616565, 46941.29588056182, 47278.76360618175, 47359.5264047362, 10006.69979900604, 10054.195664346835, 10080.243981701255, 10160.390376577428, 10166.542505587266, 17692.911535442498, 17886.837355571846, 17912.28350865977, 17915.283388664568, 17925.282988680567, 7032.05067315916, 7080.256573059857, 7221.74723884672, 7254.5647261164495, 7483.13943896448, 3823.847046118124, 3835.4438606401914, 3843.3466310727244, 3851.2105018471148, 3852.3451013327804, 2897.884084636591, 2980.538016607429, 2982.8657710403086, 2985.2536865783554, 2987.0889378739694, 475.7835184990821, 503.25518233015345, 542.5415523882314, 563.7012383436785, 578.9247397838254, 597.6742675242003, 602.9035354343284, 607.9361667024987, 618.1870839845589, 626.2922897126309, 8569.528675922884, 8768.386212965032, 8797.51613661242, 8976.775580610538, 9156.084391560924, 609.8993666045053, 620.9906851397233, 624.500399680254, 625.8341539492038, 634.0647544871371, 3537.1864471171775, 4487.214737420938, 4730.314104454835, 4741.786619602126, 4801.807927682854, 58949.0, 61317.693411533546, 61599.0, 62196.68901655463, 63380.36619633837, 51742.22386664203, 52878.735606322494, 53477.732611336694, 53699.0, 56780.43219567752, 50181.0, 50558.0, 50891.236631450556, 51382.743086285074, 51938.74030629823, 8656.48061116335, 8851.822963540822, 8879.91120088791, 8916.15296546827, 9198.172164505228, 33251.50122748112, 33404.498932516515, 33577.66422335745, 33836.32327353489, 34484.82757586196, 39467.80266098651, 40373.0, 42087.158256835304, 42874.57125428706, 42914.35628465513, 11355.943220283845, 11470.139739519402, 11551.826722599335, 11648.94175529117, 12056.09579281575, 11662.88337116618, 12020.459079341626, 12115.515379384726, 12228.816567751668, 12915.870841291468, 11549.595764148215, 12103.75792484163, 12121.515139394678, 12122.743396151873, 12238.204516706386, 11253.606123785628, 11535.653930382103, 11623.476943537731, 12101.878981210428, 12111.939440303095, 11707.765844683228, 12199.146059776085, 12228.449719762453, 12231.0, 12748.49006039748, 8757.824843503222, 9010.504422256576, 9253.982061973313, 9268.0, 9490.751086281813, 9258.814823703353, 9606.087421694929, 9822.852657210291, 10208.336458130198, 10256.81887222816], "value": 6331.852762122089, "unit": "score"}], "name": "dromaeo_css", "value": 6433.370287819029, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 113.0, 113.0, 114.0, 112.0, 114.0, 114.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 129.0, 152.0, 215.0, 129.0, 161.0, 155.0, 137.0, 195.0, 129.0], "value": 156.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 167.0, 162.0, 162.0, 161.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 155.0, 77.0, 80.0, 76.0, 75.0, 152.0, 75.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 147.0, 94.0, 94.0, 94.0, 118.0, 92.0, 93.0, 129.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 63.0, 63.0, 64.0, 65.0, 126.0, 62.0, 62.0], "value": 69.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 66.0, 64.0, 65.0, 65.0, 66.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 88.0, 82.0, 85.0, 82.0, 80.0, 82.0, 83.0, 87.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 138.0, 138.0, 139.0, 137.0, 127.0, 128.0, 142.0, 218.0, 130.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 172.0, 173.0, 179.0, 168.0, 165.0, 169.0, 167.0, 167.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 74.0, 65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1583.7, "alertThreshold": 2.0}]} 17:58:19 INFO - Return code: 0 17:58:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:58:19 ERROR - # TBPL SUCCESS # 17:58:19 INFO - Running post-action listener: _resource_record_post_action 17:58:19 INFO - ##### 17:58:19 INFO - ##### Finished run-tests step (success) 17:58:19 INFO - ##### 17:58:19 INFO - Running post-run listener: _resource_record_post_run 17:58:19 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530198528; Read time: 16; Write time: 463384 17:58:19 INFO - TinderboxPrint: CPU usage
13.1% 17:58:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 17:58:19 INFO - TinderboxPrint: I/O write bytes / time
530,198,528 / 463,384 17:58:19 INFO - TinderboxPrint: CPU idle
8,337.9 (86.9%) 17:58:19 INFO - TinderboxPrint: CPU user
1,211.4 (12.6%) 17:58:19 INFO - TinderboxPrint: Swap in / out
0 / 0 17:58:19 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236638208; Read time: 0; Write time: 251700 17:58:19 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293249024; Read time: 16; Write time: 211660 17:58:19 INFO - Running post-run listener: _upload_blobber_files 17:58:19 INFO - Blob upload gear active. 17:58:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:58:19 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 17:58:19 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'] 17:58:19 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 17:58:20 INFO - (blobuploader) - INFO - Open directory for files ... 17:58:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:58:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:58:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:24 INFO - (blobuploader) - INFO - Done attempting. 17:58:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:58:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:58:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:24 INFO - (blobuploader) - INFO - Done attempting. 17:58:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:58:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:58:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:25 INFO - (blobuploader) - INFO - Done attempting. 17:58:25 INFO - (blobuploader) - INFO - Iteration through files over. 17:58:25 INFO - Return code: 0 17:58:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:58:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:58:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/53f03f52a25e70a1f4646ae2abcdf7c6e7a00a6a7f39fd393ac6ddf75763d736d031c462ff705338a59536354f61c1d823b3697b781df69b8333f91f23d01ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb59b3215bf896e43d9b5e56a602d8ab3eb9735582ca0d50fa5d653ff6934c5c9b6166ae94ed77e25ef0d6edbbbe9b7fb4e1733c2910972d6f59d6e15c1d88e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18d0c223e48abc691079d55a1e6703e98f5c432d661bcfdab732e9e93b30e5ca447ad0f02099df9f7974e6dcb73fa48396cb84509803aee267d07faba0761c47"} 17:58:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:58:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:58:25 INFO - Contents: 17:58:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/53f03f52a25e70a1f4646ae2abcdf7c6e7a00a6a7f39fd393ac6ddf75763d736d031c462ff705338a59536354f61c1d823b3697b781df69b8333f91f23d01ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb59b3215bf896e43d9b5e56a602d8ab3eb9735582ca0d50fa5d653ff6934c5c9b6166ae94ed77e25ef0d6edbbbe9b7fb4e1733c2910972d6f59d6e15c1d88e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18d0c223e48abc691079d55a1e6703e98f5c432d661bcfdab732e9e93b30e5ca447ad0f02099df9f7974e6dcb73fa48396cb84509803aee267d07faba0761c47"} 17:58:25 INFO - Running post-run listener: copy_logs_to_upload_dir 17:58:25 INFO - Copying logs to upload dir... 17:58:25 INFO - mkdir: /builds/slave/test/build/upload/logs 17:58:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.308734 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-18 17:58:25.416199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 17:58:25.419892) ========= 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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 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/53f03f52a25e70a1f4646ae2abcdf7c6e7a00a6a7f39fd393ac6ddf75763d736d031c462ff705338a59536354f61c1d823b3697b781df69b8333f91f23d01ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb59b3215bf896e43d9b5e56a602d8ab3eb9735582ca0d50fa5d653ff6934c5c9b6166ae94ed77e25ef0d6edbbbe9b7fb4e1733c2910972d6f59d6e15c1d88e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18d0c223e48abc691079d55a1e6703e98f5c432d661bcfdab732e9e93b30e5ca447ad0f02099df9f7974e6dcb73fa48396cb84509803aee267d07faba0761c47"} build_url:https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/H3lDrMp7QKadYpBpwyki2Q/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/53f03f52a25e70a1f4646ae2abcdf7c6e7a00a6a7f39fd393ac6ddf75763d736d031c462ff705338a59536354f61c1d823b3697b781df69b8333f91f23d01ecc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb59b3215bf896e43d9b5e56a602d8ab3eb9735582ca0d50fa5d653ff6934c5c9b6166ae94ed77e25ef0d6edbbbe9b7fb4e1733c2910972d6f59d6e15c1d88e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/18d0c223e48abc691079d55a1e6703e98f5c432d661bcfdab732e9e93b30e5ca447ad0f02099df9f7974e6dcb73fa48396cb84509803aee267d07faba0761c47"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 17:58:25.445376) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:58:25.445767) ========= 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=d2a8c569965ab8367867b893000001a1-1463616640.165885-492330048 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004595 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 17:58:25.530190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 17:58:25.530735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 17:58:25.531137) ========= ========= Total master_lag: 0.19 =========