builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-039 starttime: 1464219015.68 results: success (0) buildid: 20160525153953 builduid: 4a947a504c3b426ba6f100c9d80b30a5 revision: 9ef45b3ae61d40b772319a314205ddacfe00cff9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.680823) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.681411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.682249) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.717292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.717769) ========= 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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-25 16:30:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.4M=0s 2016-05-25 16:30:15 (38.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.806903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.807489) ========= 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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018257 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.859717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:15.860150) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9ef45b3ae61d40b772319a314205ddacfe00cff9 --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 9ef45b3ae61d40b772319a314205ddacfe00cff9 --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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-25 16:30:16,027 truncating revision to first 12 chars 2016-05-25 16:30:16,027 Setting DEBUG logging. 2016-05-25 16:30:16,027 attempt 1/10 2016-05-25 16:30:16,027 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9ef45b3ae61d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 16:30:16,335 unpacking tar archive at: fx-team-9ef45b3ae61d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.613729 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:16.488514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:16.488832) ========= script_repo_revision: 9ef45b3ae61d40b772319a314205ddacfe00cff9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:16.489246) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:16.489533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 16:30:16.499257) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-25 16:30:16.499632) ========= /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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:30:16 INFO - MultiFileLogger online at 20160525 16:30:16 in /builds/slave/test 16:30:16 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 16:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:30:16 INFO - {'append_to_log': False, 16:30:16 INFO - 'base_work_dir': '/builds/slave/test', 16:30:16 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:30:16 INFO - 'branch': 'Fx-Team-Non-PGO', 16:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:30:16 INFO - 'config_files': ('talos/linux_config.py',), 16:30:16 INFO - 'default_actions': ('clobber', 16:30:16 INFO - 'read-buildbot-config', 16:30:16 INFO - 'download-and-extract', 16:30:16 INFO - 'populate-webroot', 16:30:16 INFO - 'create-virtualenv', 16:30:16 INFO - 'install', 16:30:16 INFO - 'run-tests'), 16:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:30:16 INFO - 'download_minidump_stackwalk': True, 16:30:16 INFO - 'download_symbols': 'ondemand', 16:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:30:16 INFO - 'installer_path': 'installer.exe', 16:30:16 INFO - 'log_level': 'info', 16:30:16 INFO - 'log_name': 'talos', 16:30:16 INFO - 'log_to_console': True, 16:30:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:30:16 INFO - 'opt_config_files': (), 16:30:16 INFO - 'pip_index': False, 16:30:16 INFO - 'sps_profile': False, 16:30:16 INFO - 'sps_profile_interval': 0, 16:30:16 INFO - 'suite': 'dromaeojs', 16:30:16 INFO - 'system_bits': '32', 16:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:30:16 INFO - 'title': 'talos-linux64-ix-039', 16:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:30:16 INFO - 'use_talos_json': True, 16:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:30:16 INFO - 'work_dir': 'build'} 16:30:16 INFO - ##### 16:30:16 INFO - ##### Running clobber step. 16:30:16 INFO - ##### 16:30:16 INFO - Running pre-action listener: _resource_record_pre_action 16:30:16 INFO - Running main action method: clobber 16:30:16 INFO - rmtree: /builds/slave/test/build 16:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:30:17 INFO - Running post-action listener: _resource_record_post_action 16:30:17 INFO - ##### 16:30:17 INFO - ##### Finished clobber step (success) 16:30:17 INFO - ##### 16:30:17 INFO - ##### 16:30:17 INFO - ##### Running read-buildbot-config step. 16:30:17 INFO - ##### 16:30:17 INFO - Running pre-action listener: _resource_record_pre_action 16:30:17 INFO - Running main action method: read_buildbot_config 16:30:17 INFO - Using buildbot properties: 16:30:17 INFO - { 16:30:17 INFO - "project": "", 16:30:17 INFO - "product": "firefox", 16:30:17 INFO - "script_repo_revision": "production", 16:30:17 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:30:17 INFO - "repository": "", 16:30:17 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 16:30:17 INFO - "buildid": "20160525153953", 16:30:17 INFO - "pgo_build": "False", 16:30:17 INFO - "basedir": "/builds/slave/test", 16:30:17 INFO - "buildnumber": 1721, 16:30:17 INFO - "slavename": "talos-linux64-ix-039", 16:30:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:30:17 INFO - "platform": "ubuntu64_hw", 16:30:17 INFO - "scheduler": "tests-fx-team-linux64-talos", 16:30:17 INFO - "branch": "fx-team", 16:30:17 INFO - "repo_path": "integration/fx-team", 16:30:17 INFO - "revision": "9ef45b3ae61d40b772319a314205ddacfe00cff9", 16:30:17 INFO - "stage_platform": "linux64", 16:30:17 INFO - "builduid": "4a947a504c3b426ba6f100c9d80b30a5", 16:30:17 INFO - "slavebuilddir": "test" 16:30:17 INFO - } 16:30:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:30:17 INFO - Running post-action listener: _resource_record_post_action 16:30:17 INFO - ##### 16:30:17 INFO - ##### Finished read-buildbot-config step (success) 16:30:17 INFO - ##### 16:30:17 INFO - ##### 16:30:17 INFO - ##### Running download-and-extract step. 16:30:17 INFO - ##### 16:30:17 INFO - Running pre-action listener: _resource_record_pre_action 16:30:17 INFO - Running main action method: download_and_extract 16:30:17 INFO - mkdir: /builds/slave/test/build/tests 16:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:17 INFO - https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:30:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:30:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:30:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:21 INFO - Downloaded 1361 bytes. 16:30:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:30:21 INFO - Using the following test package requirements: 16:30:21 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:30:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:30:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:30:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'jsshell-linux-x86_64.zip'], 16:30:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:30:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:30:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:30:21 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:30:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:30:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:30:21 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:30:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:21 INFO - https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:30:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:30:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:30:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:25 INFO - Downloaded 22836341 bytes. 16:30:25 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'] 16:30:25 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 16:30:26 INFO - Return code: 0 16:30:26 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 16:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:26 INFO - https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:30:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:30:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:30:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:40 INFO - Downloaded 22836341 bytes. 16:30:40 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'] 16:30:40 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 16:30:41 INFO - Return code: 0 16:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:41 INFO - https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:42 INFO - Downloaded 10834034 bytes. 16:30:42 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'] 16:30:42 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 16:30:42 INFO - Return code: 0 16:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:42 INFO - https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:30:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:30:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:30:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cGEYCM3pQcKT7msDfMYj4A/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 16:30:44 INFO - Downloaded 54828414 bytes. 16:30:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:30:44 INFO - mkdir: /builds/slave/test/properties 16:30:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:30:44 INFO - Writing to file /builds/slave/test/properties/build_url 16:30:44 INFO - Contents: 16:30:44 INFO - build_url:https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:30:45 INFO - Running post-action listener: _resource_record_post_action 16:30:45 INFO - Running post-action listener: set_extra_try_arguments 16:30:45 INFO - ##### 16:30:45 INFO - ##### Finished download-and-extract step (success) 16:30:45 INFO - ##### 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running populate-webroot step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: populate_webroot 16:30:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:30:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:30:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:30:45 INFO - '--tppagecycles', 16:30:45 INFO - '7'], 16:30:45 INFO - 'tests': ['tsvgm']}}, 16:30:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:30:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:30:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:30:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:30:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:30:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['damp', 'tps']}, 16:30:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'tests': ['damp', 'tps']}, 16:30:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['dromaeo_dom']}, 16:30:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:30:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'other-e10s': {'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['a11yr', 16:30:45 INFO - 'ts_paint', 16:30:45 INFO - 'tpaint', 16:30:45 INFO - 'sessionrestore', 16:30:45 INFO - 'sessionrestore_no_auto_restore', 16:30:45 INFO - 'tabpaint']}, 16:30:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['tsvgx', 16:30:45 INFO - 'tsvgr_opacity', 16:30:45 INFO - 'tart', 16:30:45 INFO - 'tscrollx', 16:30:45 INFO - 'cart']}, 16:30:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:30:45 INFO - 'tsvgr_opacity', 16:30:45 INFO - 'tart', 16:30:45 INFO - 'tscrollx', 16:30:45 INFO - 'cart']}, 16:30:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'talos_options': ['--disable-e10s'], 16:30:45 INFO - 'tests': ['tp5o']}, 16:30:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'tests': ['tp5o']}, 16:30:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'talos_options': ['--disable-e10s', 16:30:45 INFO - '--xperf_path', 16:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:45 INFO - 'tests': ['tp5n']}, 16:30:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:30:45 INFO - 'talos_options': ['--xperf_path', 16:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:30:45 INFO - 'tests': ['tp5n']}}, 16:30:45 INFO - 'talos.zip': {'path': '', 16:30:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:30:45 INFO - Running post-action listener: _resource_record_post_action 16:30:45 INFO - ##### 16:30:45 INFO - ##### Finished populate-webroot step (success) 16:30:45 INFO - ##### 16:30:45 INFO - ##### 16:30:45 INFO - ##### Running create-virtualenv step. 16:30:45 INFO - ##### 16:30:45 INFO - Running pre-action listener: _resource_record_pre_action 16:30:45 INFO - Running main action method: create_virtualenv 16:30:45 INFO - Creating virtualenv /builds/slave/test/build/venv 16:30:45 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 16:30:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:30:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:30:45 INFO - Using real prefix '/usr' 16:30:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:30:46 INFO - Installing distribute.............................................................................................................................................................................................done. 16:30:48 INFO - Installing pip.................done. 16:30:48 INFO - Return code: 0 16:30:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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')]} 16:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:48 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:30:48 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 16:30:48 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 16:30:48 INFO - Using env: {'DISPLAY': ':0', 16:30:48 INFO - 'HOME': '/home/cltbld', 16:30:48 INFO - 'LANG': 'en_US.UTF-8', 16:30:48 INFO - 'LANGUAGE': 'en_US:en', 16:30:48 INFO - 'LOGNAME': 'cltbld', 16:30:48 INFO - 'MAIL': '/var/mail/cltbld', 16:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:48 INFO - 'MOZ_NO_REMOTE': '1', 16:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:48 INFO - 'NO_EM_RESTART': '1', 16:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:48 INFO - 'PWD': '/builds/slave/test', 16:30:48 INFO - 'SHELL': '/bin/bash', 16:30:48 INFO - 'SHLVL': '1', 16:30:48 INFO - 'TERM': 'linux', 16:30:48 INFO - 'TMOUT': '86400', 16:30:48 INFO - 'USER': 'cltbld', 16:30:48 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:48 INFO - '_': '/tools/buildbot/bin/python'} 16:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:48 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 16:30:48 INFO - Cleaning up... 16:30:48 INFO - Return code: 0 16:30:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:30: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')]} 16:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30: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')]} 16:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:48 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:30:48 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 16:30:48 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 16:30:48 INFO - Using env: {'DISPLAY': ':0', 16:30:48 INFO - 'HOME': '/home/cltbld', 16:30:48 INFO - 'LANG': 'en_US.UTF-8', 16:30:48 INFO - 'LANGUAGE': 'en_US:en', 16:30:48 INFO - 'LOGNAME': 'cltbld', 16:30:48 INFO - 'MAIL': '/var/mail/cltbld', 16:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:48 INFO - 'MOZ_NO_REMOTE': '1', 16:30:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:48 INFO - 'NO_EM_RESTART': '1', 16:30:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:48 INFO - 'PWD': '/builds/slave/test', 16:30:48 INFO - 'SHELL': '/bin/bash', 16:30:48 INFO - 'SHLVL': '1', 16:30:48 INFO - 'TERM': 'linux', 16:30:48 INFO - 'TMOUT': '86400', 16:30:48 INFO - 'USER': 'cltbld', 16:30:48 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:48 INFO - '_': '/tools/buildbot/bin/python'} 16:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:48 INFO - Downloading/unpacking psutil>=3.1.1 16:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:50 INFO - Installing collected packages: psutil 16:30:50 INFO - Running setup.py install for psutil 16:30:50 INFO - building 'psutil._psutil_linux' extension 16:30:50 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 16:30:50 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 16:30:50 INFO - building 'psutil._psutil_posix' extension 16:30:50 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 16:30:50 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 16:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:30:50 INFO - Successfully installed psutil 16:30:50 INFO - Cleaning up... 16:30:51 INFO - Return code: 0 16:30:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:30:51 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')]} 16:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:51 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')]} 16:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:51 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:30:51 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 16:30:51 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 16:30:51 INFO - Using env: {'DISPLAY': ':0', 16:30:51 INFO - 'HOME': '/home/cltbld', 16:30:51 INFO - 'LANG': 'en_US.UTF-8', 16:30:51 INFO - 'LANGUAGE': 'en_US:en', 16:30:51 INFO - 'LOGNAME': 'cltbld', 16:30:51 INFO - 'MAIL': '/var/mail/cltbld', 16:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:51 INFO - 'MOZ_NO_REMOTE': '1', 16:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:51 INFO - 'NO_EM_RESTART': '1', 16:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:51 INFO - 'PWD': '/builds/slave/test', 16:30:51 INFO - 'SHELL': '/bin/bash', 16:30:51 INFO - 'SHLVL': '1', 16:30:51 INFO - 'TERM': 'linux', 16:30:51 INFO - 'TMOUT': '86400', 16:30:51 INFO - 'USER': 'cltbld', 16:30:51 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:51 INFO - '_': '/tools/buildbot/bin/python'} 16:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:30:51 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 16:30:51 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 16:30:51 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 16:30:51 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 16:30:51 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 16:30:51 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 16:30:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:30:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:30:53 INFO - Installing collected packages: mozsystemmonitor 16:30:53 INFO - Running setup.py install for mozsystemmonitor 16:30:53 INFO - Successfully installed mozsystemmonitor 16:30:53 INFO - Cleaning up... 16:30:53 INFO - Return code: 0 16:30:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:30:53 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')]} 16:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:53 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')]} 16:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:53 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:30:53 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 16:30:53 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 16:30:53 INFO - Using env: {'DISPLAY': ':0', 16:30:53 INFO - 'HOME': '/home/cltbld', 16:30:53 INFO - 'LANG': 'en_US.UTF-8', 16:30:53 INFO - 'LANGUAGE': 'en_US:en', 16:30:53 INFO - 'LOGNAME': 'cltbld', 16:30:53 INFO - 'MAIL': '/var/mail/cltbld', 16:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:53 INFO - 'MOZ_NO_REMOTE': '1', 16:30:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:53 INFO - 'NO_EM_RESTART': '1', 16:30:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:53 INFO - 'PWD': '/builds/slave/test', 16:30:53 INFO - 'SHELL': '/bin/bash', 16:30:53 INFO - 'SHLVL': '1', 16:30:53 INFO - 'TERM': 'linux', 16:30:53 INFO - 'TMOUT': '86400', 16:30:53 INFO - 'USER': 'cltbld', 16:30:53 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:53 INFO - '_': '/tools/buildbot/bin/python'} 16:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:30:53 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 16:30:53 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 16:30:53 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 16:30:53 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 16:30:53 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 16:30:53 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 16:30:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:30:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:30:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30: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 16:30:56 INFO - Downloading docopt-0.6.1.tar.gz 16:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:30:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:30:56 INFO - Running setup.py install for blobuploader 16:30:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:30:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:30:56 INFO - Running setup.py install for requests 16:30:56 INFO - Running setup.py install for docopt 16:30:56 INFO - Successfully installed blobuploader requests docopt 16:30:56 INFO - Cleaning up... 16:30:56 INFO - Return code: 0 16:30:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:30:56 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:30:56 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 16:30:56 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 16:30:56 INFO - Using env: {'DISPLAY': ':0', 16:30:56 INFO - 'HOME': '/home/cltbld', 16:30:56 INFO - 'LANG': 'en_US.UTF-8', 16:30:56 INFO - 'LANGUAGE': 'en_US:en', 16:30:56 INFO - 'LOGNAME': 'cltbld', 16:30:56 INFO - 'MAIL': '/var/mail/cltbld', 16:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:56 INFO - 'MOZ_NO_REMOTE': '1', 16:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:56 INFO - 'NO_EM_RESTART': '1', 16:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:56 INFO - 'PWD': '/builds/slave/test', 16:30:56 INFO - 'SHELL': '/bin/bash', 16:30:56 INFO - 'SHLVL': '1', 16:30:56 INFO - 'TERM': 'linux', 16:30:56 INFO - 'TMOUT': '86400', 16:30:56 INFO - 'USER': 'cltbld', 16:30:56 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:56 INFO - '_': '/tools/buildbot/bin/python'} 16:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:30:57 INFO - Running setup.py (path:/tmp/pip-WiH1Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:30:57 INFO - Running setup.py (path:/tmp/pip-Em4dbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:30:57 INFO - Running setup.py (path:/tmp/pip-mpZyt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:30:57 INFO - Running setup.py (path:/tmp/pip-SBUDaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:30:57 INFO - Running setup.py (path:/tmp/pip-oXq6Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:30:57 INFO - Running setup.py (path:/tmp/pip-Gwezpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:30:57 INFO - Running setup.py (path:/tmp/pip-VV_u0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:30:57 INFO - Running setup.py (path:/tmp/pip-oFaYnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:30:57 INFO - Running setup.py (path:/tmp/pip-uNaTHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:30:57 INFO - Running setup.py (path:/tmp/pip-yVq54Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:30:57 INFO - Running setup.py (path:/tmp/pip-oYdQlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:30:57 INFO - Running setup.py (path:/tmp/pip-sHiFoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:30:57 INFO - Running setup.py (path:/tmp/pip-nLrak4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:30:57 INFO - Running setup.py (path:/tmp/pip-sK7wGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:58 INFO - Running setup.py (path:/tmp/pip-1j2F3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:30:58 INFO - Running setup.py (path:/tmp/pip-MchkxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:30:58 INFO - Running setup.py (path:/tmp/pip-UzHDjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:30:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:30:58 INFO - Running setup.py install for manifestparser 16:30:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:30:58 INFO - Running setup.py install for mozcrash 16:30:58 INFO - Running setup.py install for mozdebug 16:30:58 INFO - Running setup.py install for mozdevice 16:30:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:30:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:30:58 INFO - Running setup.py install for mozfile 16:30:58 INFO - Running setup.py install for mozhttpd 16:30:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:30:58 INFO - Running setup.py install for mozinfo 16:30:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:30:58 INFO - Running setup.py install for mozInstall 16:30:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Running setup.py install for mozleak 16:30:59 INFO - Running setup.py install for mozlog 16:30:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Running setup.py install for moznetwork 16:30:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Running setup.py install for mozprocess 16:30:59 INFO - Running setup.py install for mozprofile 16:30:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Running setup.py install for mozrunner 16:30:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:30:59 INFO - Running setup.py install for mozscreenshot 16:30:59 INFO - Running setup.py install for moztest 16:30:59 INFO - Running setup.py install for mozversion 16:31:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:31:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:00 INFO - Cleaning up... 16:31:00 INFO - Return code: 0 16:31:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:00 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:31:00 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 16:31:00 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 16:31:00 INFO - Using env: {'DISPLAY': ':0', 16:31:00 INFO - 'HOME': '/home/cltbld', 16:31:00 INFO - 'LANG': 'en_US.UTF-8', 16:31:00 INFO - 'LANGUAGE': 'en_US:en', 16:31:00 INFO - 'LOGNAME': 'cltbld', 16:31:00 INFO - 'MAIL': '/var/mail/cltbld', 16:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:00 INFO - 'MOZ_NO_REMOTE': '1', 16:31:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:00 INFO - 'NO_EM_RESTART': '1', 16:31:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:00 INFO - 'PWD': '/builds/slave/test', 16:31:00 INFO - 'SHELL': '/bin/bash', 16:31:00 INFO - 'SHLVL': '1', 16:31:00 INFO - 'TERM': 'linux', 16:31:00 INFO - 'TMOUT': '86400', 16:31:00 INFO - 'USER': 'cltbld', 16:31:00 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:00 INFO - '_': '/tools/buildbot/bin/python'} 16:31:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:00 INFO - Running setup.py (path:/tmp/pip-8S3INv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:00 INFO - Running setup.py (path:/tmp/pip-zFydHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:00 INFO - Running setup.py (path:/tmp/pip-9hci4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:00 INFO - Running setup.py (path:/tmp/pip-GOt6Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:00 INFO - Running setup.py (path:/tmp/pip-UnoTmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:00 INFO - Running setup.py (path:/tmp/pip-OcdANe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:00 INFO - Running setup.py (path:/tmp/pip-rLHrWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:00 INFO - Running setup.py (path:/tmp/pip-7iAb8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:00 INFO - Running setup.py (path:/tmp/pip-xsGOUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:00 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)) 16:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:00 INFO - Running setup.py (path:/tmp/pip-qJvMvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:01 INFO - Running setup.py (path:/tmp/pip-TSDd3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:01 INFO - Running setup.py (path:/tmp/pip-p4SwYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:01 INFO - Running setup.py (path:/tmp/pip-eqOpz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:01 INFO - Running setup.py (path:/tmp/pip-sz0l9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:01 INFO - Running setup.py (path:/tmp/pip-kRwiL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:01 INFO - Running setup.py (path:/tmp/pip-ODy3yO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:01 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)) 16:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:01 INFO - Running setup.py (path:/tmp/pip-wAqkzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:01 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)) 16:31:01 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)) 16:31:01 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)) 16:31:01 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)) 16:31:01 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)) 16:31:01 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)) 16:31:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:01 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 16:31:01 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 16:31:01 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 16:31:01 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 16:31:01 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 16:31:01 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 16:31:03 INFO - Downloading blessings-1.6.tar.gz 16:31:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:31:03 INFO - Installing collected packages: blessings 16:31:03 INFO - Running setup.py install for blessings 16:31:03 INFO - Successfully installed blessings 16:31:03 INFO - Cleaning up... 16:31:03 INFO - Return code: 0 16:31:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:31:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:03 INFO - Reading from file tmpfile_stdout 16:31:03 INFO - Current package versions: 16:31:03 INFO - argparse == 1.2.1 16:31:03 INFO - blessings == 1.6 16:31:03 INFO - blobuploader == 1.2.4 16:31:03 INFO - docopt == 0.6.1 16:31:03 INFO - manifestparser == 1.1 16:31:03 INFO - mozInstall == 1.12 16:31:03 INFO - mozcrash == 0.17 16:31:03 INFO - mozdebug == 0.1 16:31:03 INFO - mozdevice == 0.48 16:31:03 INFO - mozfile == 1.2 16:31:03 INFO - mozhttpd == 0.7 16:31:03 INFO - mozinfo == 0.9 16:31:03 INFO - mozleak == 0.1 16:31:03 INFO - mozlog == 3.2 16:31:03 INFO - moznetwork == 0.27 16:31:03 INFO - mozprocess == 0.22 16:31:03 INFO - mozprofile == 0.28 16:31:03 INFO - mozrunner == 6.11 16:31:03 INFO - mozscreenshot == 0.1 16:31:03 INFO - mozsystemmonitor == 0.3 16:31:03 INFO - moztest == 0.7 16:31:03 INFO - mozversion == 1.4 16:31:03 INFO - psutil == 3.1.1 16:31:03 INFO - requests == 1.2.3 16:31:03 INFO - wsgiref == 0.1.2 16:31:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:03 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:31:03 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 16:31:03 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 16:31:03 INFO - Using env: {'DISPLAY': ':0', 16:31:03 INFO - 'HOME': '/home/cltbld', 16:31:03 INFO - 'LANG': 'en_US.UTF-8', 16:31:03 INFO - 'LANGUAGE': 'en_US:en', 16:31:03 INFO - 'LOGNAME': 'cltbld', 16:31:03 INFO - 'MAIL': '/var/mail/cltbld', 16:31:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:03 INFO - 'MOZ_NO_REMOTE': '1', 16:31:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:03 INFO - 'NO_EM_RESTART': '1', 16:31:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:03 INFO - 'PWD': '/builds/slave/test', 16:31:03 INFO - 'SHELL': '/bin/bash', 16:31:03 INFO - 'SHLVL': '1', 16:31:03 INFO - 'TERM': 'linux', 16:31:03 INFO - 'TMOUT': '86400', 16:31:03 INFO - 'USER': 'cltbld', 16:31:03 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:31:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:03 INFO - '_': '/tools/buildbot/bin/python'} 16:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 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)) 16:31:04 INFO - Cleaning up... 16:31:04 INFO - Return code: 0 16:31:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:31: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')]} 16:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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')]} 16:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:04 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:31:04 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 16:31:04 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 16:31:04 INFO - Using env: {'DISPLAY': ':0', 16:31:04 INFO - 'HOME': '/home/cltbld', 16:31:04 INFO - 'LANG': 'en_US.UTF-8', 16:31:04 INFO - 'LANGUAGE': 'en_US:en', 16:31:04 INFO - 'LOGNAME': 'cltbld', 16:31:04 INFO - 'MAIL': '/var/mail/cltbld', 16:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:04 INFO - 'MOZ_NO_REMOTE': '1', 16:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:04 INFO - 'NO_EM_RESTART': '1', 16:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:04 INFO - 'PWD': '/builds/slave/test', 16:31:04 INFO - 'SHELL': '/bin/bash', 16:31:04 INFO - 'SHLVL': '1', 16:31:04 INFO - 'TERM': 'linux', 16:31:04 INFO - 'TMOUT': '86400', 16:31:04 INFO - 'USER': 'cltbld', 16:31:04 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:04 INFO - '_': '/tools/buildbot/bin/python'} 16:31:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:04 INFO - Downloading/unpacking jsonschema 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:04 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 16:31:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:06 INFO - Downloading/unpacking functools32 (from jsonschema) 16:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:06 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:06 INFO - warning: no files found matching '*.txt' 16:31:06 INFO - no previously-included directories found matching 'build' 16:31:06 INFO - no previously-included directories found matching 'dist' 16:31:06 INFO - no previously-included directories found matching '.git*' 16:31:06 INFO - Installing collected packages: jsonschema, functools32 16:31:06 INFO - Running setup.py install for functools32 16:31:06 INFO - warning: no files found matching '*.txt' 16:31:06 INFO - no previously-included directories found matching 'build' 16:31:06 INFO - no previously-included directories found matching 'dist' 16:31:06 INFO - no previously-included directories found matching '.git*' 16:31:06 INFO - Successfully installed jsonschema functools32 16:31:06 INFO - Cleaning up... 16:31:06 INFO - Return code: 0 16:31:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:31:06 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')]} 16:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:06 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')]} 16:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:06 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 0x7f6deed97f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13f5770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, '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': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', '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 16:31:06 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 16:31:06 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 16:31:06 INFO - Using env: {'DISPLAY': ':0', 16:31:06 INFO - 'HOME': '/home/cltbld', 16:31:06 INFO - 'LANG': 'en_US.UTF-8', 16:31:06 INFO - 'LANGUAGE': 'en_US:en', 16:31:06 INFO - 'LOGNAME': 'cltbld', 16:31:06 INFO - 'MAIL': '/var/mail/cltbld', 16:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:06 INFO - 'MOZ_NO_REMOTE': '1', 16:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:06 INFO - 'NO_EM_RESTART': '1', 16:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:06 INFO - 'PWD': '/builds/slave/test', 16:31:06 INFO - 'SHELL': '/bin/bash', 16:31:06 INFO - 'SHLVL': '1', 16:31:06 INFO - 'TERM': 'linux', 16:31:06 INFO - 'TMOUT': '86400', 16:31:06 INFO - 'USER': 'cltbld', 16:31:06 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:06 INFO - '_': '/tools/buildbot/bin/python'} 16:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:06 INFO - Downloading/unpacking flake8 16:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:31:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:31:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:31:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:31:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:31:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:31:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:31:09 INFO - no previously-included directories found matching 'docs/_build' 16:31:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:31:09 INFO - Running setup.py install for pep8 16:31:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:31:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:31:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:31:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:31:09 INFO - no previously-included directories found matching 'docs/_build' 16:31:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:31:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:31:09 INFO - Cleaning up... 16:31:09 INFO - Return code: 0 16:31:09 INFO - Running post-action listener: _resource_record_post_action 16:31:09 INFO - Running post-action listener: _start_resource_monitoring 16:31:09 INFO - Starting resource monitoring. 16:31:09 INFO - ##### 16:31:09 INFO - ##### Finished create-virtualenv step (success) 16:31:09 INFO - ##### 16:31:09 INFO - ##### 16:31:09 INFO - ##### Running install step. 16:31:09 INFO - ##### 16:31:09 INFO - Running pre-action listener: _resource_record_pre_action 16:31:09 INFO - Running main action method: install 16:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:10 INFO - Reading from file tmpfile_stdout 16:31:10 INFO - Detecting whether we're running mozinstall >=1.0... 16:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:31:10 INFO - Reading from file tmpfile_stdout 16:31:10 INFO - Output received: 16:31:10 INFO - Usage: mozinstall [options] installer 16:31:10 INFO - Options: 16:31:10 INFO - -h, --help show this help message and exit 16:31:10 INFO - -d DEST, --destination=DEST 16:31:10 INFO - Directory to install application into. [default: 16:31:10 INFO - "/builds/slave/test"] 16:31:10 INFO - --app=APP Application being installed. [default: firefox] 16:31:10 INFO - mkdir: /builds/slave/test/build/application 16:31:10 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'] 16:31:10 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 16:31:25 INFO - Reading from file tmpfile_stdout 16:31:25 INFO - Output received: 16:31:25 INFO - /builds/slave/test/build/application/firefox/firefox 16:31:25 INFO - Running post-action listener: _resource_record_post_action 16:31:25 INFO - ##### 16:31:25 INFO - ##### Finished install step (success) 16:31:25 INFO - ##### 16:31:25 INFO - ##### 16:31:25 INFO - ##### Running run-tests step. 16:31:25 INFO - ##### 16:31:25 INFO - Running pre-action listener: _resource_record_pre_action 16:31:25 INFO - Running main action method: run_tests 16:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:31:25 INFO - Python 2.7.3 16:31:25 INFO - Return code: 0 16:31:25 INFO - grabbing minidump binary from tooltool 16:31:25 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')]} 16:31:25 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 0x14849c0>, '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 0x14858c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1485d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:31:25 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 16:31:25 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 16:31:25 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 16:31:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:31:25 INFO - Return code: 0 16:31:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:31:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:31:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:31:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:31:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:31:26 INFO - Return code: 0 16:31:26 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/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 16:31:26 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/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 16:31:26 INFO - Using env: {'DISPLAY': ':0', 16:31:26 INFO - 'HOME': '/home/cltbld', 16:31:26 INFO - 'LANG': 'en_US.UTF-8', 16:31:26 INFO - 'LANGUAGE': 'en_US:en', 16:31:26 INFO - 'LOGNAME': 'cltbld', 16:31:26 INFO - 'MAIL': '/var/mail/cltbld', 16:31:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:31:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:26 INFO - 'MOZ_NO_REMOTE': '1', 16:31:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:31:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:26 INFO - 'NO_EM_RESTART': '1', 16:31:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:26 INFO - 'PWD': '/builds/slave/test', 16:31:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:31:26 INFO - 'SHELL': '/bin/bash', 16:31:26 INFO - 'SHLVL': '1', 16:31:26 INFO - 'TERM': 'linux', 16:31:26 INFO - 'TMOUT': '86400', 16:31:26 INFO - 'USER': 'cltbld', 16:31:26 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625', 16:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:26 INFO - '_': '/tools/buildbot/bin/python'} 16:31:26 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/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 16:31:27 INFO - mozversion application_buildid: 20160525153953 16:31:27 INFO - mozversion application_changeset: 9ef45b3ae61d40b772319a314205ddacfe00cff9 16:31:27 INFO - mozversion application_display_name: Nightly 16:31:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:31:27 INFO - mozversion application_name: Firefox 16:31:27 INFO - mozversion application_remotingname: firefox 16:31:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:31:27 INFO - mozversion application_vendor: Mozilla 16:31:27 INFO - mozversion application_version: 49.0a1 16:31:27 INFO - mozversion platform_buildid: 20160525153953 16:31:27 INFO - mozversion platform_changeset: 9ef45b3ae61d40b772319a314205ddacfe00cff9 16:31:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:31:27 INFO - mozversion platform_version: 49.0a1 16:31:27 INFO - using testdate: 1464219087 16:31:27 INFO - actual date: 1464219087 16:31:27 INFO - starting webserver on 'localhost:37897' 16:31:27 INFO - SUITE-START | Running 2 tests 16:31:27 INFO - TEST-START | dromaeo_css 16:31:27 INFO - operating with platform_type : linux_ 16:31:27 INFO - Initialising browser for dromaeo_css test... 16:31:27 INFO - TEST-INFO | started process 24157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmLJ7Uo/profile http://localhost:37897/getInfo.html) 16:31:31 INFO - PROCESS | 24157 | __metrics Screen width/height:1600/1200 16:31:31 INFO - PROCESS | 24157 | colorDepth:24 16:31:31 INFO - PROCESS | 24157 | Browser inner width/height: 1024/697 16:31:31 INFO - PROCESS | 24157 | __metrics 16:31:32 ERROR - PROCESS | 24157 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:31:33 INFO - TEST-INFO | 24157: exit 0 16:31:33 INFO - Browser initialized. 16:31:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:31:33 INFO - TEST-INFO | started process 24241 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmLJ7Uo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:31:45 INFO - PROCESS | 24241 | RSS: Main: 156676096 16:31:45 INFO - PROCESS | 24241 | 16:32:37 INFO - PROCESS | 24241 | 1464219157208 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] 16:34:48 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37897/tests/dromaeo/cssquery-ext.html) 16:34:50 INFO - PROCESS | 24241 | RSS: Main: 277139456 16:34:50 INFO - PROCESS | 24241 | 16:37:52 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-ext.html (next: http://localhost:37897/tests/dromaeo/cssquery-jquery.html) 16:37:53 INFO - PROCESS | 24241 | RSS: Main: 238911488 16:37:53 INFO - PROCESS | 24241 | 16:40:55 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37897/tests/dromaeo/cssquery-mootools.html) 16:40:56 INFO - PROCESS | 24241 | RSS: Main: 247173120 16:40:56 INFO - PROCESS | 24241 | 16:43:58 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37897/tests/dromaeo/cssquery-prototype.html) 16:43:59 INFO - PROCESS | 24241 | RSS: Main: 234360832 16:43:59 INFO - PROCESS | 24241 | 16:47:02 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37897/tests/dromaeo/cssquery-yui.html) 16:47:02 INFO - PROCESS | 24241 | RSS: Main: 216965120 16:47:02 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | Cycle 1(1): loaded http://localhost:37897/tests/dromaeo/cssquery-yui.html (next: http://localhost:37897/tests/dromaeo/cssquery-dojo.html) 16:50:05 INFO - PROCESS | 24241 | RSS: Main: 269635584 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | __start_tp_report 16:50:05 INFO - PROCESS | 24241 | _x_x_mozilla_page_load 16:50:05 INFO - PROCESS | 24241 | _x_x_mozilla_page_load_details 16:50:05 INFO - PROCESS | 24241 | |i|pagename|runs| 16:50:05 INFO - PROCESS | 24241 | |0;dojo.html;2650.655414796076;2672.786177105832;2702.891884324627;2713.5251331017066;2719.9048033318827;4518.277075667894;4550.271956486963;4579.908401831962;4638.698484598499;4708.293755936607;4578.839740609079;4616.7765542131365;4628.537146285378;4635.026644404671;4645.837395691143;4240.363945408182;4298.484181898177;4318.913621727564;4350.303951367781;4493.078918821634;4430.756308403036;4452.532484089173;4457.1977044132045;4483.125790470859;4510.503844577094;4210.768407737573;4264.424302719155;4286.164197981395;4317.546657600969;4321.956780432219;4299.54854740254;4313.568643135693;4315.395844581761;4412.713173643735;4430.1582699287355;4363.367311739812;4369.825206991716;4375.124975005012;4418.381426600278;4425.1149770045795;4426.269665505188;4573.8170473181035;4598.356230127785;4668.043051174501;4679.15775160471;5125.103106956305;5130.743462826881;5135.0500157470615;5137.948620513822;5139.357580302462;5143.974280128576;5150.9742451287875;5160.767765450564;5170.276161337416;5171.172612382001;4575.336576196433;4691.882702932454;4738.95261047392;4741.620670346364;4816.036792641451;5261.473852614785;5273.789048438096;5285.154375299933;5287.127623942083;5292.391374991926;5013.849584512471;5057.797688092435;5068.594512438996;5072.137736584789;5076.670016448919;4891.388576427947;4961.677490963076;5012.54887060159;5015.072211640858;5058.570021548209;4960;4999.725015124131;4999.825006124768;5002.174641184164;5049.899002020013;4867.148249056401;4976.402831660224;4986.626003049713;4989.301497790312;5037;4230.788460576959;4252.787360631956;4284.807183676742;4290.141971605629;4379.737215767064;3762.5861155272896;3780.678642315434;3783.7162212834146;3822;3824.349860523718;4325;4329;4340.696151269381;4381.759003254851;4384.758838263926;4916.360873086476;4945.802167913315;4993.201087825931;4996.725180115129;5044.419891712427;4235.8729238122905;4236.343366778155;4248.447701798808;4248.702590818613;4293.270144075452;4453.576910193527;4528.456585209796;4533.116042371706;4540.432445944257;4541.72749635023;4698.882527936829;4732.2428411454;4746.477887432311;4759.881002974953;4762.9523704762505;5061.4179369373;5067.847964561069;5071.518205770446;5077.1876499759865;5089.847304580869;4603.516630753723;4659.091477161921;4748.762561871962;4756.57275943926;4802.615790736802;4912.435069966999;4936.136176169228;4951.3810773653295;4972.254161875748;4981.800727970841;5042.39491261051;5055.6713813602;5056.089903817348;5058.266551350107;5068.721220332917;4809.9038019240115;4825.565699087099;4838.104950584013;4906.533879281463;4964.528369804862;4864.440589332272;4885.975570122127;4895.63282753802;4923.187674033744;4932.334134891744;4826.227803551415;4829.806807727651;4835.830745923876;4874.390701162355;4881.926771098508;4756.928646070242;4760.428748550196;4859.489753575965;4896.290037944407;4961.2806143109765;4702.835400760957;4799.3760811094335;4808.0383923214795;4814.518548145157;4833.903321933612 16:50:05 INFO - PROCESS | 24241 | |1;ext.html;539.442478908285;593.5133190783554;595.4789559135756;596.5019208960517;599.4125756758375;39100.804495977514;39781.2043759125;40062.79968600156;40526;40543.59456405435;38027.80986095073;42679.09190443092;42924;42992.785036074856;43053.56946430535;7501.099868015818;7704.6918123275145;7884.369250459949;7890.842183156334;10102.19182465401;9677.467739274338;9819.26355523335;10142.797144057115;10144;10144.188464922789;10119.342242754234;10241.078302952732;10249.333793303449;10250;10251.897481025206;10041.447720375376;10113.292069555135;10132.493375331203;10138.645147419842;10143.89856101437;10131.784185897666;10233.027862353065;10242.23183261251;10247.436390998491;10255.6410525632;39316.80341598302;39727.20545589072;39837.601623983675;39901;39966.40050399247;3005.353848922492;3035.2715348316465;3091.412631600011;3120.563121163039;3226.080567038383;29690.25774355637;30202.697973020207;30599.541006884916;30608.081757547086;30734.538981915288;3486.6339034401276;3494.5107684924133;3553.1117220694828;3599.4960705501253;3619.2580520993133;260130;262518.9999999981;263339;263590;263857;57351.13973290404;57651.423485765445;57712.71143644255;57865;57949.710251449316;62006.68996654988;62293.688531557054;62374.37625623686;62545.68727156335;62555.6872215636;13933.721325573431;14368.066075705046;14388.136711797117;14390;14397.78403323951;9550.856737148948;11033.944830275797;11176.60881869147;11177.049950754277;11639.359835209145;18687;18715.157817898227;19525.60948781045;19560.804391956182;19601.607967840562;7278;7298.708051677873;7319.780406587811;7384.2246564111065;7505.887411688829;864.6282098697494;917.9672868023473;965.9806803863884;990.2276224544867;1029.063552167539;26568.335791605365;27509.587356189673;28331.716682832906;28334.43331133407;28409.57385639217;966.2752935298527;968.1673760565908;970.7912798748303;997.9900200998043;1035.3735989726108;14382.640433989234;14712.852871471363;14732.116073035651;15113.54659360199;15219.695606087816;382238;387888;388538;389056;389284;48928;50190.49809501931;50302.4909252723;50327;50359.24461133086;1088.0099109810035;1184.8483035126521;1221.0597839663233;1223.8714934931882;1243.272685478987;27650.5852412218;28849.711502884704;28879.5668064975;29084.854575726986;29743.25641858971;41870;41939.161216776105;42360.15279694327;42481.57518424776;42490;307138;310006;310049;311151;311509;31477.213069672525;32423.351532968736;37094;38076.42885356777;38080.61919380771;41746.791266043474;42310;42922.78538607287;43003.56996429995;43209;35877.28245435024;35880.64119358773;36225.27549449049;36756.81621591875;36827.631723683495;35661;35737.1065723359;36087.45868811917;36099;36313.45529817108;35199;35286.647133528335;35353.646463535035;35510;36220.4566931491;31842.68157318397;32109.357812843144;32246.19384515406;32386.676133238365;32747.34505309833;25165.622515661915;25200.243992680247;25353.87323063373;25522.468651880947;25572 16:50:05 INFO - PROCESS | 24241 | |2;jquery.html;366.72312404134874;370.1523511159445;373.5815886207448;374.1973466913469;380.7658290151556;15854.60363490912;16406.343746250164;16672;16741.33034678613;17149.571260718472;14927.253637318145;15100.773488397683;15387.846121538782;15415.69168616627;15462.304196311165;4253.340732186516;4543.772811359436;4544.454665440152;4578.771061446914;4578.908421831561;3462.1344663834043;3631.4189729643263;4499.055198408339;4956.1822299320565;5307.655002424849;5306.044911915854;5326.440723723992;5339.813106541272;5350.117230656937;5353.812616558421;4995.150824359867;5277.419483856792;5318.069337865858;5319.361676598833;5328.46715328466;5032.924506132411;5044.445111037783;5044.798208071709;5050.772715227824;5053.545180933734;13543.932280338633;13990.580282591538;19670.40988770339;20260.392188234375;20281.087351069236;1860.48836569943;1862.10618902926;1946.6593346164364;1978.8021197880241;2170.3814412892407;8729.956350218208;8829.337799664987;8871.733847984506;9181.632734690671;9220.723378298662;1748.5978225008191;1763.8500727438184;1797.1912639312231;1811.3026484803313;1849.1493912800129;61980;62689.37310626881;65047;66516.33483665198;70053.64973175102;9235.722928312027;9398.624055037722;9497.905020949702;9671.371360861522;9743.707688769347;13279;13365.064445488917;13375.464981400635;13383.464661413629;13385.531506397228;7134.215236324;7163.78508644731;7307.3058059484265;7492.625368731542;7495.400367970549;7059.364657180776;7077.292270772985;7139.393151582174;7255.927440725631;7270.054892863894;4088.264112459786;4092.4475195848777;4092.6930480214105;4207.22166399211;4239.385289133058;1615.3134917659947;1687.797464304267;1723.922423490921;1783.8126996665164;1786.59801544654;745.7136439641683;811.4198348181079;850.2857599616352;862.7498025572639;903.814717982812;15817.683646327008;16335.67328653444;16685;16810.747838782183;17219.913900430667;841.1000229754104;874.6938571499924;880.4365206267994;887.4630848336792;906.7370462565914;9263.212626926652;9385.15533601979;9537.380070295547;9612.9425763166;9764.951175244221;366167;366268;370543;381240;381921;72303.99999999895;72625.63687181531;73122.26877731261;73189;74011.25988740043;819.4100247821559;853.4154104438479;878.5958459108881;879.6657270237268;880.2605811118691;12126.27242365461;14435.92782036083;14667.413303467742;15407.537773866801;15810.76283855766;32986.34027319488;33770.49344259887;34821;35117.068561229375;35262.647373525935;44846.10307793761;46201.76899115483;46206.537934620224;46933.949293295824;47015.529844701116;7590.368070864791;7627.351675107678;7682.116556596063;7788.805279868048;7830.9819723435585;10838.295510791773;11693.941530292293;11747.118966077749;11861.5848445304;12229.633111006684;9717.368371055858;9998.800023999334;10094.697159085239;10134.986501349806;10265.692029239135;10855.620053298097;10898.618548350769;10959.178061645567;11032.834507482556;11079.833802492807;7255.600941948243;8288.58557072132;9415.529223538933;9841.704748857546;10174.949125254327;7529.962350188214;7532.322091011835;7717.729879454192;7723.575203363869;7935.523868567904;9663;9757.804843903225;9759.316847820584;9771.38772102595;9889.604415823287 16:50:05 INFO - PROCESS | 24241 | |3;mootools.html;1284.0882973089106;1447.9710405791884;1482.6738117614125;1630.6494103767689;1809.9819001809985;6713.395794378505;7405.1854296027395;7657.846843063142;7690;7701.114371847239;7441.9255807441905;7500.5874676892745;7598.582077985709;7737.651805668745;7766.805829854242;1896.4405500377352;2136.2309568555347;2176.1948079210715;2193.254293540196;2200.04298130313;2229.8662080275235;2260.4348912771807;2262.016023029987;2263.9320820375415;2266.7393249776324;2150.7204063471727;2156.5579056293423;2162.50262439639;2231.5313784105356;2233.2630232023394;1806.4038867173801;2107.525806693497;2167.1548096242323;2168.793964573363;2204.9559008819815;2162.610730068587;2184.868907865533;2186.628273193545;2186.7157269554855;2187.3765976696563;7628.8855667165035;7676.884846727304;7685.269899359552;7685.923140768577;7690;4222.3244280914905;4350.042990542076;4350.238708226079;4357.7821108944745;4361.280388735855;2711.769499592537;2856.814307070034;2869.7130286971346;2879.4960881845805;2886.9278268043468;1747.475757272813;1783.2599471219428;1797.5506123469133;1800.540862080161;1847.5104097414196;10583.84124238137;10807.67576972692;10829.675109746642;10851.728706782314;10853.403062831541;4187.790610469464;4254.106637606137;4308.418363520947;4327.437433133736;4342.82628694855;9254.676086336945;9257.583408746623;9269.490178040138;9269.67556135532;9287.024862389488;4451.132029254264;4458.175237581057;4458.308962110878;4466.865994020184;4469.351943968107;3488.162840918212;3890.2025084857537;3988.2422339755353;3992.740471869319;4005.078831868628;3676.356637588411;3714.7213958953184;3765.1151979284846;3792.905177370588;3831.7317787755146;1176.2413243457922;1184.1829137894824;1197.9401029948467;1198.7353342224037;1202.4228370382266;712.5617745086735;792.4730054513799;802.6187560908638;805.0500409516709;822.2229992656881;4626.676132670681;4830.3479030331155;5016.297718319438;5020.422651395136;5091.363579552556;865.3769286113987;875.2647775868301;906.959186836594;910.9433057653091;947.6114792934864;3290.8190049547025;3307.6692330767214;3325.4513005353847;3336.747695305659;3337.399268131711;9248.583813728399;9468.289878259158;9540.379875308083;9551.761205969906;9571.28215383849;9418.387804792666;9468.384555003902;9504.619815207314;9506.334556580972;9540.85688714656;7309.305615966475;7324.230955749569;7452.590107544137;7505.737299194502;7518.210587888302;4931.605471562339;4935.185694360391;4955.157623204136;4965.652404331662;4982.501749824988;8917.375783695079;8934.195922367018;9087.363884528284;9175.174234318943;9181.035991221093;8624.568771561271;8913.019567847661;9130.178283954476;9187.08129187076;9204.585793639168;5794.072948328416;6373.521985850987;6535.607863528204;6543.083968244355;6720.697568609522;7410.333070023724;7472.514286571355;7493.250674932463;7538.133114691881;7556.5088269264525;6405.295417504164;6511.413972742286;6567.638779866962;6647.268800431852;6673.632950187786;6753.898691519533;6776.220734615582;6776.356246156607;6804.251532331341;6816.659167041529;6245.406686364788;6272.529560283088;6294.464970477561;6308.148399965946;6321.146645202839;4164.895877603084;4409.514953355064;4428.977855110704;4467.7989490472355;4475.351074094303;4665.930011049769;4671.346011558317;4721.315409265569;4727.409073865767;4740.620750340034 16:50:05 INFO - PROCESS | 24241 | |4;prototype.html;275.9130873774761;283.60720402242885;284.53336528093917;287.7022281938194;293.62416107382546;4628.606568441678;4773.856784296472;4788.640851936101;4806.9519304806945;4888.9266661000065;4590.219662657348;4736.668433209677;4752.500987396325;4766.808297925519;4792.880177995552;3737.2338670572467;3749.793761343132;3759.191773768639;3759.8308076136504;3782.508273924386;3576.5350504434386;3631.7094632429475;3751.4994002398985;3773.3019391412677;3791.4502397152396;3733.5893051764283;3738.9626103739024;3740.1584643455276;3740.962590374102;3742.4012158054716;3703.4815125882396;3714.294284086015;3724.0689827543115;3730.7388482806214;3769.830357633913;3307.908390231242;3313.6023677158896;3317.4526203176447;3326.800391976489;3352.3630510202984;4545.386372839657;4585.633149348044;4589.816407343702;4605.332226827091;4623.283391074388;4257.659387249013;4259.3610958356185;4268.359746038119;4272.743635381856;4280.186764514732;5009.674371165863;5017.448080711119;5017.623678224111;5036.64010717108;5058.873528161788;3489.4940233665975;3491.6857482826667;3498.247876706495;3499.142710036032;3606.0804494853646;5181.248718935771;5216.973915130438;5227.60792940531;5238.345206849144;5255.316808814868;3589.2642008388216;3599.226166374242;3600.3159399713973;3601.8928926577723;3611.0972256935765;3583.3549961006747;3673.2653469306247;3673.6142705015586;3680.705543556509;3687.575928768226;4196.265653510684;4206.705530612828;4217.325227963573;4218;4225.767582782915;4989.950100498949;4994.700317980933;4998.925016124761;5008.774605142778;5010.874728131826;1210.9878901211052;1238.9256644601353;1275.3941877608063;1276.9361531923366;1281.916675416095;805.7824387415483;816.7427260412951;832.820943497151;836.7531578184451;839.2656425627575;3952.8023598819946;3953.892909985209;3974.82113304902;3989.3617021276455;3991.1818077294083;4574.817007319736;4586.656000799953;4604.58558729716;4626.866417727679;4639.468975238164;3943.861964831217;3944.230874979409;3952.565217826037;3976.622220889011;3994.8601798936897;2364.6571247169063;2426.3500819442947;2466.5436894174636;2468.5435593000216;2489.6265560165757;4363.781810909441;4371.060222052274;4377.956220437819;4381.452318460192;4383.342498625232;4677.46209185948;4688.624910007191;4718.504557021532;4721.220998535203;4734.526547345306;4195.056112374691;4306.827726890952;4403.273459879084;4431.601155895984;4432.977835110868;4551.40569875168;4628.352030715635;4655.208614535605;4693.272542755878;4717.245240761462;4529.844889553143;4530.637548996138;4536.478304994967;4551.725516855286;4580.221362368338;4060.837566497425;4338.522762496186;4365.249892567662;4368.885934086915;4403.669724770719;3367.7202662987906;3372.1401042733946;3384.8984530464127;3391.4912763085736;3392.796432214075;4618.307253911806;4630.791614377428;4640.559146881041;4658.184817656965;5010.724410157476;4887.780049897834;5017.065016965116;5022.347094877642;5030.320906677698;5054.924176137434;4956.207006878882;5013.24801279811;5033.395992480926;5038.1435156022835;5096.949030509648;4856.490068542894;4983.202687569971;4998.500749625187;5020.774065167003;5025.949740502549;5051.722155281494;5109.156989096758;5117.513836185556;5133.460986596354;5134.486551344836;4976.800927962841;4983.327250821238;5036.395632524121;5045.445001049955;5045.596352291881 16:50:05 INFO - PROCESS | 24241 | |5;yui.html;622.2253294648164;738.4978214813924;740.5889731199184;756.1304499825201;762.5958242131672;12097.758044839098;12605.873941260585;12760.425780839862;12891.419886105125;12912.612621621343;11843.7631247375;11873.7625247495;11911.225770324923;11912.64262072137;11924.523019079248;625.3059104394118;772.2817779465096;779.8830175473696;784.9293563579276;787.4173111897212;809.134226377776;815.9428839981204;816.2327412232512;816.5590581086207;816.7141500474846;843.6836186430089;844.8099177685033;848.1476116502937;848.5121055393142;850.5619605902965;658.5554750543388;809.8096947217399;833.8749187621845;846.3440833354139;846.5555583318745;615.8521954730877;616.7132283488155;616.9598976066542;616.9629822210682;617.0713076819358;13065.346732663424;13077.738445231042;13093.541726039542;13105.541306054338;13114.409851556702;11841.703957401047;12111.878881211163;12225.755484890342;12263.632091037283;12604.936975315064;11163.83195807865;11190.608328708457;11302.321860688386;11327.603533876274;11452.599159029478;4083.7141400102005;4106.917861642781;4132.40080188371;4172.269852775752;4256.191323648528;42854.78572607117;42896;42949.57050429518;43151.13697726059;43157;9730.185916052436;9767.853482197772;9959.203263738884;10359.741006474747;10359.844602330972;16323.428679996143;16439.83560164407;16440;16445.753313700305;16451.42420015294;6756.358145976124;6823.795286141423;6868.896966545506;6909.516333856582;6956.478264130109;3720.981395093007;3726.2547490501465;3774.6602805747066;3776.754510956779;3895.7467764595212;2678.7053424123333;2764.5162096633007;2764.7649949754095;2766.681831589353;2767.3081729567607;490.0859896293387;494.38449130832345;500.86226287771154;502.48746278796153;505.5879458241595;457.5013235573241;477.0108646942259;496.38448324078604;506.07641055074635;533.3306848879683;7251.202367739545;7265.8546829063125;7373.188949215582;7383.704651813868;7444.330010299099;399.4986292203266;416.40454150564983;423.9957600424018;451.58454222115734;455.00127220750136;3324.983375083109;3369.814660193713;3435.484677298375;3439.8796042138406;3456.5160877477174;46414;48117.27824082642;48333;52153.47846521562;52881;47692;49032.50967490351;49219.26171107365;49582;49883.25175122376;45815.312770308476;45957.31064034042;46092.769536152104;46361.84095397575;46513.53486465092;7911.446198766032;7956.92043079585;7991.120976692443;8056.919430805851;8059.556724380215;30790.230244244074;31024.68975310218;31114.22214444657;31248.843755781076;31426.371472569965;36715.08212294607;38378.42432363461;38681.41977870278;39157;39395;8719.738407847775;10935.671929842118;10993.505292262027;11025.44872756375;11047.668569942914;9371.953140234255;9561.84858406853;9605.519724013911;9787.951060244937;9811.509424528887;8954.731358059269;9106.180443760093;9110.271178305587;9194;9279.350445468752;8951.194392504705;9032.638694452411;9056.864147037668;9104.863427048733;9114.225290850352;9187.448753074837;9303.534823258675;9304.860427093463;9578.47318397467;9728.124468797841;7579;7709.807254818675;7734.458587898793;7746.0317460317465;7980.680772769257;7959.801004974922;7975.481593696391;8083.151269116894;8099.797505062421;8194.795130121795 16:50:05 INFO - PROCESS | 24241 | __end_tp_report 16:50:05 INFO - PROCESS | 24241 | __start_cc_report 16:50:05 INFO - PROCESS | 24241 | _x_x_mozilla_cycle_collect,1124 16:50:05 INFO - PROCESS | 24241 | __end_cc_report 16:50:05 INFO - PROCESS | 24241 | __startTimestamp1464220205652__endTimestamp 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | ------- Summary: start ------- 16:50:05 INFO - PROCESS | 24241 | Number of tests: 6 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#0] dojo.html Cycles:165 Average:4655.04 Median:4756.93 stddev:482.10 (10.1%) stddev-sans-first:457.22 16:50:05 INFO - PROCESS | 24241 | Values: 2650.7 2672.8 2702.9 2713.5 2719.9 4518.3 4550.3 4579.9 4638.7 4708.3 4578.8 4616.8 4628.5 4635.0 4645.8 4240.4 4298.5 4318.9 4350.3 4493.1 4430.8 4452.5 4457.2 4483.1 4510.5 4210.8 4264.4 4286.2 4317.5 4322.0 4299.5 4313.6 4315.4 4412.7 4430.2 4363.4 4369.8 4375.1 4418.4 4425.1 4426.3 4573.8 4598.4 4668.0 4679.2 5125.1 5130.7 5135.1 5137.9 5139.4 5144.0 5151.0 5160.8 5170.3 5171.2 4575.3 4691.9 4739.0 4741.6 4816.0 5261.5 5273.8 5285.2 5287.1 5292.4 5013.8 5057.8 5068.6 5072.1 5076.7 4891.4 4961.7 5012.5 5015.1 5058.6 4960.0 4999.7 4999.8 5002.2 5049.9 4867.1 4976.4 4986.6 4989.3 5037.0 4230.8 4252.8 4284.8 4290.1 4379.7 3762.6 3780.7 3783.7 3822.0 3824.3 4325.0 4329.0 4340.7 4381.8 4384.8 4916.4 4945.8 4993.2 4996.7 5044.4 4235.9 4236.3 4248.4 4248.7 4293.3 4453.6 4528.5 4533.1 4540.4 4541.7 4698.9 4732.2 4746.5 4759.9 4763.0 5061.4 5067.8 5071.5 5077.2 5089.8 4603.5 4659.1 4748.8 4756.6 4802.6 4912.4 4936.1 4951.4 4972.3 4981.8 5042.4 5055.7 5056.1 5058.3 5068.7 4809.9 4825.6 4838.1 4906.5 4964.5 4864.4 4886.0 4895.6 4923.2 4932.3 4826.2 4829.8 4835.8 4874.4 4881.9 4756.9 4760.4 4859.5 4896.3 4961.3 4702.8 4799.4 4808.0 4814.5 4833.9 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#1] ext.html Cycles:180 Average:48620.71 Median:35320.15 stddev:85083.70 (240.9%) stddev-sans-first:85245.80 16:50:05 INFO - PROCESS | 24241 | Values: 539.4 593.5 595.5 596.5 599.4 39100.8 39781.2 40062.8 40526.0 40543.6 38027.8 42679.1 42924.0 42992.8 43053.6 7501.1 7704.7 7884.4 7890.8 10102.2 9677.5 9819.3 10142.8 10144.0 10144.2 10119.3 10241.1 10249.3 10250.0 10251.9 10041.4 10113.3 10132.5 10138.6 10143.9 10131.8 10233.0 10242.2 10247.4 10255.6 39316.8 39727.2 39837.6 39901.0 39966.4 3005.4 3035.3 3091.4 3120.6 3226.1 29690.3 30202.7 30599.5 30608.1 30734.5 3486.6 3494.5 3553.1 3599.5 3619.3 260130.0 262519.0 263339.0 263590.0 263857.0 57351.1 57651.4 57712.7 57865.0 57949.7 62006.7 62293.7 62374.4 62545.7 62555.7 13933.7 14368.1 14388.1 14390.0 14397.8 9550.9 11033.9 11176.6 11177.0 11639.4 18687.0 18715.2 19525.6 19560.8 19601.6 7278.0 7298.7 7319.8 7384.2 7505.9 864.6 918.0 966.0 990.2 1029.1 26568.3 27509.6 28331.7 28334.4 28409.6 966.3 968.2 970.8 998.0 1035.4 14382.6 14712.9 14732.1 15113.5 15219.7 382238.0 387888.0 388538.0 389056.0 389284.0 48928.0 50190.5 50302.5 50327.0 50359.2 1088.0 1184.8 1221.1 1223.9 1243.3 27650.6 28849.7 28879.6 29084.9 29743.3 41870.0 41939.2 42360.2 42481.6 42490.0 307138.0 310006.0 310049.0 311151.0 311509.0 31477.2 32423.4 37094.0 38076.4 38080.6 41746.8 42310.0 42922.8 43003.6 43209.0 35877.3 35880.6 36225.3 36756.8 36827.6 35661.0 35737.1 36087.5 36099.0 36313.5 35199.0 35286.6 35353.6 35510.0 36220.5 31842.7 32109.4 32246.2 32386.7 32747.3 25165.6 25200.2 25353.9 25522.5 25572.0 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#2] jquery.html Cycles:180 Average:23120.43 Median:46974.74 stddev:61564.99 (131.1%) stddev-sans-first:61713.99 16:50:05 INFO - PROCESS | 24241 | Values: 366.7 370.2 373.6 374.2 380.8 15854.6 16406.3 16672.0 16741.3 17149.6 14927.3 15100.8 15387.8 15415.7 15462.3 4253.3 4543.8 4544.5 4578.8 4578.9 3462.1 3631.4 4499.1 4956.2 5307.7 5306.0 5326.4 5339.8 5350.1 5353.8 4995.2 5277.4 5318.1 5319.4 5328.5 5032.9 5044.4 5044.8 5050.8 5053.5 13543.9 13990.6 19670.4 20260.4 20281.1 1860.5 1862.1 1946.7 1978.8 2170.4 8730.0 8829.3 8871.7 9181.6 9220.7 1748.6 1763.9 1797.2 1811.3 1849.1 61980.0 62689.4 65047.0 66516.3 70053.6 9235.7 9398.6 9497.9 9671.4 9743.7 13279.0 13365.1 13375.5 13383.5 13385.5 7134.2 7163.8 7307.3 7492.6 7495.4 7059.4 7077.3 7139.4 7255.9 7270.1 4088.3 4092.4 4092.7 4207.2 4239.4 1615.3 1687.8 1723.9 1783.8 1786.6 745.7 811.4 850.3 862.7 903.8 15817.7 16335.7 16685.0 16810.7 17219.9 841.1 874.7 880.4 887.5 906.7 9263.2 9385.2 9537.4 9612.9 9765.0 366167.0 366268.0 370543.0 381240.0 381921.0 72304.0 72625.6 73122.3 73189.0 74011.3 819.4 853.4 878.6 879.7 880.3 12126.3 14435.9 14667.4 15407.5 15810.8 32986.3 33770.5 34821.0 35117.1 35262.6 44846.1 46201.8 46206.5 46933.9 47015.5 7590.4 7627.4 7682.1 7788.8 7831.0 10838.3 11693.9 11747.1 11861.6 12229.6 9717.4 9998.8 10094.7 10135.0 10265.7 10855.6 10898.6 10959.2 11032.8 11079.8 7255.6 8288.6 9415.5 9841.7 10174.9 7530.0 7532.3 7717.7 7723.6 7935.5 9663.0 9757.8 9759.3 9771.4 9889.6 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#3] mootools.html Cycles:180 Average:5100.54 Median:4785.48 stddev:2842.75 (59.4%) stddev-sans-first:2836.25 16:50:05 INFO - PROCESS | 24241 | Values: 1284.1 1448.0 1482.7 1630.6 1810.0 6713.4 7405.2 7657.8 7690.0 7701.1 7441.9 7500.6 7598.6 7737.7 7766.8 1896.4 2136.2 2176.2 2193.3 2200.0 2229.9 2260.4 2262.0 2263.9 2266.7 2150.7 2156.6 2162.5 2231.5 2233.3 1806.4 2107.5 2167.2 2168.8 2205.0 2162.6 2184.9 2186.6 2186.7 2187.4 7628.9 7676.9 7685.3 7685.9 7690.0 4222.3 4350.0 4350.2 4357.8 4361.3 2711.8 2856.8 2869.7 2879.5 2886.9 1747.5 1783.3 1797.6 1800.5 1847.5 10583.8 10807.7 10829.7 10851.7 10853.4 4187.8 4254.1 4308.4 4327.4 4342.8 9254.7 9257.6 9269.5 9269.7 9287.0 4451.1 4458.2 4458.3 4466.9 4469.4 3488.2 3890.2 3988.2 3992.7 4005.1 3676.4 3714.7 3765.1 3792.9 3831.7 1176.2 1184.2 1197.9 1198.7 1202.4 712.6 792.5 802.6 805.1 822.2 4626.7 4830.3 5016.3 5020.4 5091.4 865.4 875.3 907.0 910.9 947.6 3290.8 3307.7 3325.5 3336.7 3337.4 9248.6 9468.3 9540.4 9551.8 9571.3 9418.4 9468.4 9504.6 9506.3 9540.9 7309.3 7324.2 7452.6 7505.7 7518.2 4931.6 4935.2 4955.2 4965.7 4982.5 8917.4 8934.2 9087.4 9175.2 9181.0 8624.6 8913.0 9130.2 9187.1 9204.6 5794.1 6373.5 6535.6 6543.1 6720.7 7410.3 7472.5 7493.3 7538.1 7556.5 6405.3 6511.4 6567.6 6647.3 6673.6 6753.9 6776.2 6776.4 6804.3 6816.7 6245.4 6272.5 6294.5 6308.1 6321.1 4164.9 4409.5 4429.0 4467.8 4475.4 4665.9 4671.3 4721.3 4727.4 4740.6 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#4] prototype.html Cycles:180 Average:4010.53 Median:4393.31 stddev:1163.22 (26.5%) stddev-sans-first:1132.21 16:50:05 INFO - PROCESS | 24241 | Values: 275.9 283.6 284.5 287.7 293.6 4628.6 4773.9 4788.6 4807.0 4888.9 4590.2 4736.7 4752.5 4766.8 4792.9 3737.2 3749.8 3759.2 3759.8 3782.5 3576.5 3631.7 3751.5 3773.3 3791.5 3733.6 3739.0 3740.2 3741.0 3742.4 3703.5 3714.3 3724.1 3730.7 3769.8 3307.9 3313.6 3317.5 3326.8 3352.4 4545.4 4585.6 4589.8 4605.3 4623.3 4257.7 4259.4 4268.4 4272.7 4280.2 5009.7 5017.4 5017.6 5036.6 5058.9 3489.5 3491.7 3498.2 3499.1 3606.1 5181.2 5217.0 5227.6 5238.3 5255.3 3589.3 3599.2 3600.3 3601.9 3611.1 3583.4 3673.3 3673.6 3680.7 3687.6 4196.3 4206.7 4217.3 4218.0 4225.8 4990.0 4994.7 4998.9 5008.8 5010.9 1211.0 1238.9 1275.4 1276.9 1281.9 805.8 816.7 832.8 836.8 839.3 3952.8 3953.9 3974.8 3989.4 3991.2 4574.8 4586.7 4604.6 4626.9 4639.5 3943.9 3944.2 3952.6 3976.6 3994.9 2364.7 2426.4 2466.5 2468.5 2489.6 4363.8 4371.1 4378.0 4381.5 4383.3 4677.5 4688.6 4718.5 4721.2 4734.5 4195.1 4306.8 4403.3 4431.6 4433.0 4551.4 4628.4 4655.2 4693.3 4717.2 4529.8 4530.6 4536.5 4551.7 4580.2 4060.8 4338.5 4365.2 4368.9 4403.7 3367.7 3372.1 3384.9 3391.5 3392.8 4618.3 4630.8 4640.6 4658.2 5010.7 4887.8 5017.1 5022.3 5030.3 5054.9 4956.2 5013.2 5033.4 5038.1 5096.9 4856.5 4983.2 4998.5 5020.8 5025.9 5051.7 5109.2 5117.5 5133.5 5134.5 4976.8 4983.3 5036.4 5045.4 5045.6 16:50:05 INFO - PROCESS | 24241 | 16:50:05 INFO - PROCESS | 24241 | [#5] yui.html Cycles:180 Average:12523.14 Median:25038.19 stddev:14550.79 (58.1%) stddev-sans-first:14564.16 16:50:05 INFO - PROCESS | 24241 | Values: 622.2 738.5 740.6 756.1 762.6 12097.8 12605.9 12760.4 12891.4 12912.6 11843.8 11873.8 11911.2 11912.6 11924.5 625.3 772.3 779.9 784.9 787.4 809.1 815.9 816.2 816.6 816.7 843.7 844.8 848.1 848.5 850.6 658.6 809.8 833.9 846.3 846.6 615.9 616.7 617.0 617.0 617.1 13065.3 13077.7 13093.5 13105.5 13114.4 11841.7 12111.9 12225.8 12263.6 12604.9 11163.8 11190.6 11302.3 11327.6 11452.6 4083.7 4106.9 4132.4 4172.3 4256.2 42854.8 42896.0 42949.6 43151.1 43157.0 9730.2 9767.9 9959.2 10359.7 10359.8 16323.4 16439.8 16440.0 16445.8 16451.4 6756.4 6823.8 6868.9 6909.5 6956.5 3721.0 3726.3 3774.7 3776.8 3895.7 2678.7 2764.5 2764.8 2766.7 2767.3 490.1 494.4 500.9 502.5 505.6 457.5 477.0 496.4 506.1 533.3 7251.2 7265.9 7373.2 7383.7 7444.3 399.5 416.4 424.0 451.6 455.0 3325.0 3369.8 3435.5 3439.9 3456.5 46414.0 48117.3 48333.0 52153.5 52881.0 47692.0 49032.5 49219.3 49582.0 49883.3 45815.3 45957.3 46092.8 46361.8 46513.5 7911.4 7956.9 7991.1 8056.9 8059.6 30790.2 31024.7 31114.2 31248.8 31426.4 36715.1 38378.4 38681.4 39157.0 39395.0 8719.7 10935.7 10993.5 11025.4 11047.7 9372.0 9561.8 9605.5 9788.0 9811.5 8954.7 9106.2 9110.3 9194.0 9279.4 8951.2 9032.6 9056.9 9104.9 9114.2 9187.4 9303.5 9304.9 9578.5 9728.1 7579.0 7709.8 7734.5 7746.0 7980.7 7959.8 7975.5 8083.2 8099.8 8194.8 16:50:05 INFO - PROCESS | 24241 | -------- Summary: end -------- 16:50:05 INFO - PROCESS | 24241 | 16:50:06 INFO - TEST-INFO | 24241: exit 0 16:50:06 INFO - TEST-OK | dromaeo_css | took 1119325ms 16:50:06 INFO - TEST-START | kraken 16:50:06 INFO - operating with platform_type : linux_ 16:50:06 INFO - Initialising browser for kraken test... 16:50:06 INFO - TEST-INFO | started process 24418 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzhmJEu/profile http://localhost:37897/getInfo.html) 16:50:10 INFO - PROCESS | 24418 | __metrics Screen width/height:1600/1200 16:50:10 INFO - PROCESS | 24418 | colorDepth:24 16:50:10 INFO - PROCESS | 24418 | Browser inner width/height: 1024/697 16:50:10 INFO - PROCESS | 24418 | __metrics 16:50:11 ERROR - PROCESS | 24418 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:50:12 INFO - TEST-INFO | 24418: exit 0 16:50:12 INFO - Browser initialized. 16:50:12 INFO - Running cycle 1/1 for kraken test... 16:50:12 INFO - TEST-INFO | started process 24502 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzhmJEu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:50:24 INFO - PROCESS | 24502 | RSS: Main: 157110272 16:50:24 INFO - PROCESS | 24502 | 16:50:27 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37897/tests/kraken/driver.html?testName=audio-beat-detection) 16:50:28 INFO - PROCESS | 24502 | RSS: Main: 212729856 16:50:28 INFO - PROCESS | 24502 | 16:50:30 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37897/tests/kraken/driver.html?testName=audio-dft) 16:50:31 INFO - PROCESS | 24502 | RSS: Main: 200925184 16:50:31 INFO - PROCESS | 24502 | 16:50:34 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37897/tests/kraken/driver.html?testName=audio-fft) 16:50:35 INFO - PROCESS | 24502 | RSS: Main: 269307904 16:50:35 INFO - PROCESS | 24502 | 16:50:37 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37897/tests/kraken/driver.html?testName=audio-oscillator) 16:50:38 INFO - PROCESS | 24502 | RSS: Main: 178085888 16:50:38 INFO - PROCESS | 24502 | 16:50:40 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37897/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:50:40 INFO - PROCESS | 24502 | RSS: Main: 183115776 16:50:40 INFO - PROCESS | 24502 | 16:50:44 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37897/tests/kraken/driver.html?testName=imaging-darkroom) 16:50:44 INFO - PROCESS | 24502 | RSS: Main: 218808320 16:50:44 INFO - PROCESS | 24502 | 16:50:48 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37897/tests/kraken/driver.html?testName=imaging-desaturate) 16:50:48 INFO - PROCESS | 24502 | RSS: Main: 216326144 16:50:48 INFO - PROCESS | 24502 | 16:50:51 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37897/tests/kraken/driver.html?testName=json-parse-financial) 16:50:52 INFO - PROCESS | 24502 | RSS: Main: 217714688 16:50:52 INFO - PROCESS | 24502 | 16:50:54 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37897/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:50:54 INFO - PROCESS | 24502 | RSS: Main: 205283328 16:50:54 INFO - PROCESS | 24502 | 16:50:56 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:50:57 INFO - PROCESS | 24502 | RSS: Main: 210608128 16:50:57 INFO - PROCESS | 24502 | 16:50:59 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:00 INFO - PROCESS | 24502 | RSS: Main: 221724672 16:51:00 INFO - PROCESS | 24502 | 16:51:02 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:03 INFO - PROCESS | 24502 | RSS: Main: 225964032 16:51:03 INFO - PROCESS | 24502 | 16:51:06 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:06 INFO - PROCESS | 24502 | RSS: Main: 223694848 16:51:06 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | Cycle 1(1): loaded http://localhost:37897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37897/tests/kraken/driver.html?testName=ai-astar) 16:51:08 INFO - PROCESS | 24502 | RSS: Main: 269127680 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | __start_tp_report 16:51:08 INFO - PROCESS | 24502 | _x_x_mozilla_page_load 16:51:08 INFO - PROCESS | 24502 | _x_x_mozilla_page_load_details 16:51:08 INFO - PROCESS | 24502 | |i|pagename|runs| 16:51:08 INFO - PROCESS | 24502 | |0;ai-astar;112;108;115;114;112;115;114;119;113;113 16:51:08 INFO - PROCESS | 24502 | |1;audio-beat-detection;166;137;131;191;129;142;154;132;191;129 16:51:08 INFO - PROCESS | 24502 | |2;audio-dft;160;161;161;160;160;161;161;162;160;161 16:51:08 INFO - PROCESS | 24502 | |3;audio-fft;77;76;75;109;76;77;76;75;99;77 16:51:08 INFO - PROCESS | 24502 | |4;audio-oscillator;85;85;118;83;85;84;121;83;84;118 16:51:08 INFO - PROCESS | 24502 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 16:51:08 INFO - PROCESS | 24502 | |6;imaging-darkroom;147;146;147;147;147;146;146;145;146;146 16:51:08 INFO - PROCESS | 24502 | |7;imaging-desaturate;95;95;94;95;94;95;95;95;94;95 16:51:08 INFO - PROCESS | 24502 | |8;json-parse-financial;64;64;64;64;65;64;64;87;63;63 16:51:08 INFO - PROCESS | 24502 | |9;json-stringify-tinderbox;66;65;66;65;67;65;67;65;67;66 16:51:08 INFO - PROCESS | 24502 | |10;stanford-crypto-aes;82;83;82;82;83;88;84;83;83;86 16:51:08 INFO - PROCESS | 24502 | |11;stanford-crypto-ccm;134;131;129;129;130;127;128;129;179;131 16:51:08 INFO - PROCESS | 24502 | |12;stanford-crypto-pbkdf2;173;176;172;175;173;174;174;174;171;168 16:51:08 INFO - PROCESS | 24502 | |13;stanford-crypto-sha256-iterative;68;66;66;67;66;65;67;69;67;68 16:51:08 INFO - PROCESS | 24502 | __end_tp_report 16:51:08 INFO - PROCESS | 24502 | __start_cc_report 16:51:08 INFO - PROCESS | 24502 | _x_x_mozilla_cycle_collect,1901 16:51:08 INFO - PROCESS | 24502 | __end_cc_report 16:51:08 INFO - PROCESS | 24502 | __startTimestamp1464220268984__endTimestamp 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | ------- Summary: start ------- 16:51:08 INFO - PROCESS | 24502 | Number of tests: 14 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:2.80 (2.5%) stddev-sans-first:2.92 16:51:08 INFO - PROCESS | 24502 | Values: 112.0 108.0 115.0 114.0 112.0 115.0 114.0 119.0 113.0 113.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:148.00 stddev:24.57 (16.6%) stddev-sans-first:25.39 16:51:08 INFO - PROCESS | 24502 | Values: 166.0 137.0 131.0 191.0 129.0 142.0 154.0 132.0 191.0 129.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 16:51:08 INFO - PROCESS | 24502 | Values: 160.0 161.0 161.0 160.0 160.0 161.0 161.0 162.0 160.0 161.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.50 stddev:12.01 (15.7%) stddev-sans-first:12.62 16:51:08 INFO - PROCESS | 24502 | Values: 77.0 76.0 75.0 109.0 76.0 77.0 76.0 75.0 99.0 77.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.00 stddev:16.87 (20.1%) stddev-sans-first:17.54 16:51:08 INFO - PROCESS | 24502 | Values: 85.0 85.0 118.0 83.0 85.0 84.0 121.0 83.0 84.0 118.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:51:08 INFO - PROCESS | 24502 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 16:51:08 INFO - PROCESS | 24502 | Values: 147.0 146.0 147.0 147.0 147.0 146.0 146.0 145.0 146.0 146.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:51:08 INFO - PROCESS | 24502 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:64.00 stddev:7.33 (11.5%) stddev-sans-first:7.73 16:51:08 INFO - PROCESS | 24502 | Values: 64.0 64.0 64.0 64.0 65.0 64.0 64.0 87.0 63.0 63.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 16:51:08 INFO - PROCESS | 24502 | Values: 66.0 65.0 66.0 65.0 67.0 65.0 67.0 65.0 67.0 66.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:1.96 (2.4%) stddev-sans-first:1.99 16:51:08 INFO - PROCESS | 24502 | Values: 82.0 83.0 82.0 82.0 83.0 88.0 84.0 83.0 83.0 86.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:130.50 stddev:15.68 (12.0%) stddev-sans-first:16.63 16:51:08 INFO - PROCESS | 24502 | Values: 134.0 131.0 129.0 129.0 130.0 127.0 128.0 129.0 179.0 131.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:174.00 stddev:2.26 (1.3%) stddev-sans-first:2.40 16:51:08 INFO - PROCESS | 24502 | Values: 173.0 176.0 172.0 175.0 173.0 174.0 174.0 174.0 171.0 168.0 16:51:08 INFO - PROCESS | 24502 | 16:51:08 INFO - PROCESS | 24502 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:1.20 16:51:08 INFO - PROCESS | 24502 | Values: 68.0 66.0 66.0 67.0 66.0 65.0 67.0 69.0 67.0 68.0 16:51:08 INFO - PROCESS | 24502 | -------- Summary: end -------- 16:51:08 INFO - PROCESS | 24502 | 16:51:09 INFO - TEST-INFO | 24502: exit 0 16:51:10 INFO - TEST-OK | kraken | took 63602ms 16:51:10 INFO - SUITE-END | took 1182s 16:51:10 INFO - Completed test suite (00:19:43) 16:51:10 INFO - javascript benchmark 16:51:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2650.655414796076, 2672.786177105832, 2702.891884324627, 2713.5251331017066, 2719.9048033318827, 4518.277075667894, 4550.271956486963, 4579.908401831962, 4638.698484598499, 4708.293755936607, 4578.839740609079, 4616.7765542131365, 4628.537146285378, 4635.026644404671, 4645.837395691143, 4240.363945408182, 4298.484181898177, 4318.913621727564, 4350.303951367781, 4493.078918821634, 4430.756308403036, 4452.532484089173, 4457.1977044132045, 4483.125790470859, 4510.503844577094, 4210.768407737573, 4264.424302719155, 4286.164197981395, 4317.546657600969, 4321.956780432219, 4299.54854740254, 4313.568643135693, 4315.395844581761, 4412.713173643735, 4430.1582699287355, 4363.367311739812, 4369.825206991716, 4375.124975005012, 4418.381426600278, 4425.1149770045795, 4426.269665505188, 4573.8170473181035, 4598.356230127785, 4668.043051174501, 4679.15775160471, 5125.103106956305, 5130.743462826881, 5135.0500157470615, 5137.948620513822, 5139.357580302462, 5143.974280128576, 5150.9742451287875, 5160.767765450564, 5170.276161337416, 5171.172612382001, 4575.336576196433, 4691.882702932454, 4738.95261047392, 4741.620670346364, 4816.036792641451, 5261.473852614785, 5273.789048438096, 5285.154375299933, 5287.127623942083, 5292.391374991926, 5013.849584512471, 5057.797688092435, 5068.594512438996, 5072.137736584789, 5076.670016448919, 4891.388576427947, 4961.677490963076, 5012.54887060159, 5015.072211640858, 5058.570021548209, 4960.0, 4999.725015124131, 4999.825006124768, 5002.174641184164, 5049.899002020013, 4867.148249056401, 4976.402831660224, 4986.626003049713, 4989.301497790312, 5037.0, 4230.788460576959, 4252.787360631956, 4284.807183676742, 4290.141971605629, 4379.737215767064, 3762.5861155272896, 3780.678642315434, 3783.7162212834146, 3822.0, 3824.349860523718, 4325.0, 4329.0, 4340.696151269381, 4381.759003254851, 4384.758838263926, 4916.360873086476, 4945.802167913315, 4993.201087825931, 4996.725180115129, 5044.419891712427, 4235.8729238122905, 4236.343366778155, 4248.447701798808, 4248.702590818613, 4293.270144075452, 4453.576910193527, 4528.456585209796, 4533.116042371706, 4540.432445944257, 4541.72749635023, 4698.882527936829, 4732.2428411454, 4746.477887432311, 4759.881002974953, 4762.9523704762505, 5061.4179369373, 5067.847964561069, 5071.518205770446, 5077.1876499759865, 5089.847304580869, 4603.516630753723, 4659.091477161921, 4748.762561871962, 4756.57275943926, 4802.615790736802, 4912.435069966999, 4936.136176169228, 4951.3810773653295, 4972.254161875748, 4981.800727970841, 5042.39491261051, 5055.6713813602, 5056.089903817348, 5058.266551350107, 5068.721220332917, 4809.9038019240115, 4825.565699087099, 4838.104950584013, 4906.533879281463, 4964.528369804862, 4864.440589332272, 4885.975570122127, 4895.63282753802, 4923.187674033744, 4932.334134891744, 4826.227803551415, 4829.806807727651, 4835.830745923876, 4874.390701162355, 4881.926771098508, 4756.928646070242, 4760.428748550196, 4859.489753575965, 4896.290037944407, 4961.2806143109765, 4702.835400760957, 4799.3760811094335, 4808.0383923214795, 4814.518548145157, 4833.903321933612], "value": 4625.231769986211, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.442478908285, 593.5133190783554, 595.4789559135756, 596.5019208960517, 599.4125756758375, 39100.804495977514, 39781.2043759125, 40062.79968600156, 40526.0, 40543.59456405435, 38027.80986095073, 42679.09190443092, 42924.0, 42992.785036074856, 43053.56946430535, 7501.099868015818, 7704.6918123275145, 7884.369250459949, 7890.842183156334, 10102.19182465401, 9677.467739274338, 9819.26355523335, 10142.797144057115, 10144.0, 10144.188464922789, 10119.342242754234, 10241.078302952732, 10249.333793303449, 10250.0, 10251.897481025206, 10041.447720375376, 10113.292069555135, 10132.493375331203, 10138.645147419842, 10143.89856101437, 10131.784185897666, 10233.027862353065, 10242.23183261251, 10247.436390998491, 10255.6410525632, 39316.80341598302, 39727.20545589072, 39837.601623983675, 39901.0, 39966.40050399247, 3005.353848922492, 3035.2715348316465, 3091.412631600011, 3120.563121163039, 3226.080567038383, 29690.25774355637, 30202.697973020207, 30599.541006884916, 30608.081757547086, 30734.538981915288, 3486.6339034401276, 3494.5107684924133, 3553.1117220694828, 3599.4960705501253, 3619.2580520993133, 260130.0, 262518.9999999981, 263339.0, 263590.0, 263857.0, 57351.13973290404, 57651.423485765445, 57712.71143644255, 57865.0, 57949.710251449316, 62006.68996654988, 62293.688531557054, 62374.37625623686, 62545.68727156335, 62555.6872215636, 13933.721325573431, 14368.066075705046, 14388.136711797117, 14390.0, 14397.78403323951, 9550.856737148948, 11033.944830275797, 11176.60881869147, 11177.049950754277, 11639.359835209145, 18687.0, 18715.157817898227, 19525.60948781045, 19560.804391956182, 19601.607967840562, 7278.0, 7298.708051677873, 7319.780406587811, 7384.2246564111065, 7505.887411688829, 864.6282098697494, 917.9672868023473, 965.9806803863884, 990.2276224544867, 1029.063552167539, 26568.335791605365, 27509.587356189673, 28331.716682832906, 28334.43331133407, 28409.57385639217, 966.2752935298527, 968.1673760565908, 970.7912798748303, 997.9900200998043, 1035.3735989726108, 14382.640433989234, 14712.852871471363, 14732.116073035651, 15113.54659360199, 15219.695606087816, 382238.0, 387888.0, 388538.0, 389056.0, 389284.0, 48928.0, 50190.49809501931, 50302.4909252723, 50327.0, 50359.24461133086, 1088.0099109810035, 1184.8483035126521, 1221.0597839663233, 1223.8714934931882, 1243.272685478987, 27650.5852412218, 28849.711502884704, 28879.5668064975, 29084.854575726986, 29743.25641858971, 41870.0, 41939.161216776105, 42360.15279694327, 42481.57518424776, 42490.0, 307138.0, 310006.0, 310049.0, 311151.0, 311509.0, 31477.213069672525, 32423.351532968736, 37094.0, 38076.42885356777, 38080.61919380771, 41746.791266043474, 42310.0, 42922.78538607287, 43003.56996429995, 43209.0, 35877.28245435024, 35880.64119358773, 36225.27549449049, 36756.81621591875, 36827.631723683495, 35661.0, 35737.1065723359, 36087.45868811917, 36099.0, 36313.45529817108, 35199.0, 35286.647133528335, 35353.646463535035, 35510.0, 36220.4566931491, 31842.68157318397, 32109.357812843144, 32246.19384515406, 32386.676133238365, 32747.34505309833, 25165.622515661915, 25200.243992680247, 25353.87323063373, 25522.468651880947, 25572.0], "value": 18552.900503020795, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.72312404134874, 370.1523511159445, 373.5815886207448, 374.1973466913469, 380.7658290151556, 15854.60363490912, 16406.343746250164, 16672.0, 16741.33034678613, 17149.571260718472, 14927.253637318145, 15100.773488397683, 15387.846121538782, 15415.69168616627, 15462.304196311165, 4253.340732186516, 4543.772811359436, 4544.454665440152, 4578.771061446914, 4578.908421831561, 3462.1344663834043, 3631.4189729643263, 4499.055198408339, 4956.1822299320565, 5307.655002424849, 5306.044911915854, 5326.440723723992, 5339.813106541272, 5350.117230656937, 5353.812616558421, 4995.150824359867, 5277.419483856792, 5318.069337865858, 5319.361676598833, 5328.46715328466, 5032.924506132411, 5044.445111037783, 5044.798208071709, 5050.772715227824, 5053.545180933734, 13543.932280338633, 13990.580282591538, 19670.40988770339, 20260.392188234375, 20281.087351069236, 1860.48836569943, 1862.10618902926, 1946.6593346164364, 1978.8021197880241, 2170.3814412892407, 8729.956350218208, 8829.337799664987, 8871.733847984506, 9181.632734690671, 9220.723378298662, 1748.5978225008191, 1763.8500727438184, 1797.1912639312231, 1811.3026484803313, 1849.1493912800129, 61980.0, 62689.37310626881, 65047.0, 66516.33483665198, 70053.64973175102, 9235.722928312027, 9398.624055037722, 9497.905020949702, 9671.371360861522, 9743.707688769347, 13279.0, 13365.064445488917, 13375.464981400635, 13383.464661413629, 13385.531506397228, 7134.215236324, 7163.78508644731, 7307.3058059484265, 7492.625368731542, 7495.400367970549, 7059.364657180776, 7077.292270772985, 7139.393151582174, 7255.927440725631, 7270.054892863894, 4088.264112459786, 4092.4475195848777, 4092.6930480214105, 4207.22166399211, 4239.385289133058, 1615.3134917659947, 1687.797464304267, 1723.922423490921, 1783.8126996665164, 1786.59801544654, 745.7136439641683, 811.4198348181079, 850.2857599616352, 862.7498025572639, 903.814717982812, 15817.683646327008, 16335.67328653444, 16685.0, 16810.747838782183, 17219.913900430667, 841.1000229754104, 874.6938571499924, 880.4365206267994, 887.4630848336792, 906.7370462565914, 9263.212626926652, 9385.15533601979, 9537.380070295547, 9612.9425763166, 9764.951175244221, 366167.0, 366268.0, 370543.0, 381240.0, 381921.0, 72303.99999999895, 72625.63687181531, 73122.26877731261, 73189.0, 74011.25988740043, 819.4100247821559, 853.4154104438479, 878.5958459108881, 879.6657270237268, 880.2605811118691, 12126.27242365461, 14435.92782036083, 14667.413303467742, 15407.537773866801, 15810.76283855766, 32986.34027319488, 33770.49344259887, 34821.0, 35117.068561229375, 35262.647373525935, 44846.10307793761, 46201.76899115483, 46206.537934620224, 46933.949293295824, 47015.529844701116, 7590.368070864791, 7627.351675107678, 7682.116556596063, 7788.805279868048, 7830.9819723435585, 10838.295510791773, 11693.941530292293, 11747.118966077749, 11861.5848445304, 12229.633111006684, 9717.368371055858, 9998.800023999334, 10094.697159085239, 10134.986501349806, 10265.692029239135, 10855.620053298097, 10898.618548350769, 10959.178061645567, 11032.834507482556, 11079.833802492807, 7255.600941948243, 8288.58557072132, 9415.529223538933, 9841.704748857546, 10174.949125254327, 7529.962350188214, 7532.322091011835, 7717.729879454192, 7723.575203363869, 7935.523868567904, 9663.0, 9757.804843903225, 9759.316847820584, 9771.38772102595, 9889.604415823287], "value": 7881.346100328579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.0882973089106, 1447.9710405791884, 1482.6738117614125, 1630.6494103767689, 1809.9819001809985, 6713.395794378505, 7405.1854296027395, 7657.846843063142, 7690.0, 7701.114371847239, 7441.9255807441905, 7500.5874676892745, 7598.582077985709, 7737.651805668745, 7766.805829854242, 1896.4405500377352, 2136.2309568555347, 2176.1948079210715, 2193.254293540196, 2200.04298130313, 2229.8662080275235, 2260.4348912771807, 2262.016023029987, 2263.9320820375415, 2266.7393249776324, 2150.7204063471727, 2156.5579056293423, 2162.50262439639, 2231.5313784105356, 2233.2630232023394, 1806.4038867173801, 2107.525806693497, 2167.1548096242323, 2168.793964573363, 2204.9559008819815, 2162.610730068587, 2184.868907865533, 2186.628273193545, 2186.7157269554855, 2187.3765976696563, 7628.8855667165035, 7676.884846727304, 7685.269899359552, 7685.923140768577, 7690.0, 4222.3244280914905, 4350.042990542076, 4350.238708226079, 4357.7821108944745, 4361.280388735855, 2711.769499592537, 2856.814307070034, 2869.7130286971346, 2879.4960881845805, 2886.9278268043468, 1747.475757272813, 1783.2599471219428, 1797.5506123469133, 1800.540862080161, 1847.5104097414196, 10583.84124238137, 10807.67576972692, 10829.675109746642, 10851.728706782314, 10853.403062831541, 4187.790610469464, 4254.106637606137, 4308.418363520947, 4327.437433133736, 4342.82628694855, 9254.676086336945, 9257.583408746623, 9269.490178040138, 9269.67556135532, 9287.024862389488, 4451.132029254264, 4458.175237581057, 4458.308962110878, 4466.865994020184, 4469.351943968107, 3488.162840918212, 3890.2025084857537, 3988.2422339755353, 3992.740471869319, 4005.078831868628, 3676.356637588411, 3714.7213958953184, 3765.1151979284846, 3792.905177370588, 3831.7317787755146, 1176.2413243457922, 1184.1829137894824, 1197.9401029948467, 1198.7353342224037, 1202.4228370382266, 712.5617745086735, 792.4730054513799, 802.6187560908638, 805.0500409516709, 822.2229992656881, 4626.676132670681, 4830.3479030331155, 5016.297718319438, 5020.422651395136, 5091.363579552556, 865.3769286113987, 875.2647775868301, 906.959186836594, 910.9433057653091, 947.6114792934864, 3290.8190049547025, 3307.6692330767214, 3325.4513005353847, 3336.747695305659, 3337.399268131711, 9248.583813728399, 9468.289878259158, 9540.379875308083, 9551.761205969906, 9571.28215383849, 9418.387804792666, 9468.384555003902, 9504.619815207314, 9506.334556580972, 9540.85688714656, 7309.305615966475, 7324.230955749569, 7452.590107544137, 7505.737299194502, 7518.210587888302, 4931.605471562339, 4935.185694360391, 4955.157623204136, 4965.652404331662, 4982.501749824988, 8917.375783695079, 8934.195922367018, 9087.363884528284, 9175.174234318943, 9181.035991221093, 8624.568771561271, 8913.019567847661, 9130.178283954476, 9187.08129187076, 9204.585793639168, 5794.072948328416, 6373.521985850987, 6535.607863528204, 6543.083968244355, 6720.697568609522, 7410.333070023724, 7472.514286571355, 7493.250674932463, 7538.133114691881, 7556.5088269264525, 6405.295417504164, 6511.413972742286, 6567.638779866962, 6647.268800431852, 6673.632950187786, 6753.898691519533, 6776.220734615582, 6776.356246156607, 6804.251532331341, 6816.659167041529, 6245.406686364788, 6272.529560283088, 6294.464970477561, 6308.148399965946, 6321.146645202839, 4164.895877603084, 4409.514953355064, 4428.977855110704, 4467.7989490472355, 4475.351074094303, 4665.930011049769, 4671.346011558317, 4721.315409265569, 4727.409073865767, 4740.620750340034], "value": 4164.383665254117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.9130873774761, 283.60720402242885, 284.53336528093917, 287.7022281938194, 293.62416107382546, 4628.606568441678, 4773.856784296472, 4788.640851936101, 4806.9519304806945, 4888.9266661000065, 4590.219662657348, 4736.668433209677, 4752.500987396325, 4766.808297925519, 4792.880177995552, 3737.2338670572467, 3749.793761343132, 3759.191773768639, 3759.8308076136504, 3782.508273924386, 3576.5350504434386, 3631.7094632429475, 3751.4994002398985, 3773.3019391412677, 3791.4502397152396, 3733.5893051764283, 3738.9626103739024, 3740.1584643455276, 3740.962590374102, 3742.4012158054716, 3703.4815125882396, 3714.294284086015, 3724.0689827543115, 3730.7388482806214, 3769.830357633913, 3307.908390231242, 3313.6023677158896, 3317.4526203176447, 3326.800391976489, 3352.3630510202984, 4545.386372839657, 4585.633149348044, 4589.816407343702, 4605.332226827091, 4623.283391074388, 4257.659387249013, 4259.3610958356185, 4268.359746038119, 4272.743635381856, 4280.186764514732, 5009.674371165863, 5017.448080711119, 5017.623678224111, 5036.64010717108, 5058.873528161788, 3489.4940233665975, 3491.6857482826667, 3498.247876706495, 3499.142710036032, 3606.0804494853646, 5181.248718935771, 5216.973915130438, 5227.60792940531, 5238.345206849144, 5255.316808814868, 3589.2642008388216, 3599.226166374242, 3600.3159399713973, 3601.8928926577723, 3611.0972256935765, 3583.3549961006747, 3673.2653469306247, 3673.6142705015586, 3680.705543556509, 3687.575928768226, 4196.265653510684, 4206.705530612828, 4217.325227963573, 4218.0, 4225.767582782915, 4989.950100498949, 4994.700317980933, 4998.925016124761, 5008.774605142778, 5010.874728131826, 1210.9878901211052, 1238.9256644601353, 1275.3941877608063, 1276.9361531923366, 1281.916675416095, 805.7824387415483, 816.7427260412951, 832.820943497151, 836.7531578184451, 839.2656425627575, 3952.8023598819946, 3953.892909985209, 3974.82113304902, 3989.3617021276455, 3991.1818077294083, 4574.817007319736, 4586.656000799953, 4604.58558729716, 4626.866417727679, 4639.468975238164, 3943.861964831217, 3944.230874979409, 3952.565217826037, 3976.622220889011, 3994.8601798936897, 2364.6571247169063, 2426.3500819442947, 2466.5436894174636, 2468.5435593000216, 2489.6265560165757, 4363.781810909441, 4371.060222052274, 4377.956220437819, 4381.452318460192, 4383.342498625232, 4677.46209185948, 4688.624910007191, 4718.504557021532, 4721.220998535203, 4734.526547345306, 4195.056112374691, 4306.827726890952, 4403.273459879084, 4431.601155895984, 4432.977835110868, 4551.40569875168, 4628.352030715635, 4655.208614535605, 4693.272542755878, 4717.245240761462, 4529.844889553143, 4530.637548996138, 4536.478304994967, 4551.725516855286, 4580.221362368338, 4060.837566497425, 4338.522762496186, 4365.249892567662, 4368.885934086915, 4403.669724770719, 3367.7202662987906, 3372.1401042733946, 3384.8984530464127, 3391.4912763085736, 3392.796432214075, 4618.307253911806, 4630.791614377428, 4640.559146881041, 4658.184817656965, 5010.724410157476, 4887.780049897834, 5017.065016965116, 5022.347094877642, 5030.320906677698, 5054.924176137434, 4956.207006878882, 5013.24801279811, 5033.395992480926, 5038.1435156022835, 5096.949030509648, 4856.490068542894, 4983.202687569971, 4998.500749625187, 5020.774065167003, 5025.949740502549, 5051.722155281494, 5109.156989096758, 5117.513836185556, 5133.460986596354, 5134.486551344836, 4976.800927962841, 4983.327250821238, 5036.395632524121, 5045.445001049955, 5045.596352291881], "value": 3641.541769891666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.2253294648164, 738.4978214813924, 740.5889731199184, 756.1304499825201, 762.5958242131672, 12097.758044839098, 12605.873941260585, 12760.425780839862, 12891.419886105125, 12912.612621621343, 11843.7631247375, 11873.7625247495, 11911.225770324923, 11912.64262072137, 11924.523019079248, 625.3059104394118, 772.2817779465096, 779.8830175473696, 784.9293563579276, 787.4173111897212, 809.134226377776, 815.9428839981204, 816.2327412232512, 816.5590581086207, 816.7141500474846, 843.6836186430089, 844.8099177685033, 848.1476116502937, 848.5121055393142, 850.5619605902965, 658.5554750543388, 809.8096947217399, 833.8749187621845, 846.3440833354139, 846.5555583318745, 615.8521954730877, 616.7132283488155, 616.9598976066542, 616.9629822210682, 617.0713076819358, 13065.346732663424, 13077.738445231042, 13093.541726039542, 13105.541306054338, 13114.409851556702, 11841.703957401047, 12111.878881211163, 12225.755484890342, 12263.632091037283, 12604.936975315064, 11163.83195807865, 11190.608328708457, 11302.321860688386, 11327.603533876274, 11452.599159029478, 4083.7141400102005, 4106.917861642781, 4132.40080188371, 4172.269852775752, 4256.191323648528, 42854.78572607117, 42896.0, 42949.57050429518, 43151.13697726059, 43157.0, 9730.185916052436, 9767.853482197772, 9959.203263738884, 10359.741006474747, 10359.844602330972, 16323.428679996143, 16439.83560164407, 16440.0, 16445.753313700305, 16451.42420015294, 6756.358145976124, 6823.795286141423, 6868.896966545506, 6909.516333856582, 6956.478264130109, 3720.981395093007, 3726.2547490501465, 3774.6602805747066, 3776.754510956779, 3895.7467764595212, 2678.7053424123333, 2764.5162096633007, 2764.7649949754095, 2766.681831589353, 2767.3081729567607, 490.0859896293387, 494.38449130832345, 500.86226287771154, 502.48746278796153, 505.5879458241595, 457.5013235573241, 477.0108646942259, 496.38448324078604, 506.07641055074635, 533.3306848879683, 7251.202367739545, 7265.8546829063125, 7373.188949215582, 7383.704651813868, 7444.330010299099, 399.4986292203266, 416.40454150564983, 423.9957600424018, 451.58454222115734, 455.00127220750136, 3324.983375083109, 3369.814660193713, 3435.484677298375, 3439.8796042138406, 3456.5160877477174, 46414.0, 48117.27824082642, 48333.0, 52153.47846521562, 52881.0, 47692.0, 49032.50967490351, 49219.26171107365, 49582.0, 49883.25175122376, 45815.312770308476, 45957.31064034042, 46092.769536152104, 46361.84095397575, 46513.53486465092, 7911.446198766032, 7956.92043079585, 7991.120976692443, 8056.919430805851, 8059.556724380215, 30790.230244244074, 31024.68975310218, 31114.22214444657, 31248.843755781076, 31426.371472569965, 36715.08212294607, 38378.42432363461, 38681.41977870278, 39157.0, 39395.0, 8719.738407847775, 10935.671929842118, 10993.505292262027, 11025.44872756375, 11047.668569942914, 9371.953140234255, 9561.84858406853, 9605.519724013911, 9787.951060244937, 9811.509424528887, 8954.731358059269, 9106.180443760093, 9110.271178305587, 9194.0, 9279.350445468752, 8951.194392504705, 9032.638694452411, 9056.864147037668, 9104.863427048733, 9114.225290850352, 9187.448753074837, 9303.534823258675, 9304.860427093463, 9578.47318397467, 9728.124468797841, 7579.0, 7709.807254818675, 7734.458587898793, 7746.0317460317465, 7980.680772769257, 7959.801004974922, 7975.481593696391, 8083.151269116894, 8099.797505062421, 8194.795130121795], "value": 5686.292150790484, "unit": "score"}], "name": "dromaeo_css", "value": 6227.457897282981, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 115.0, 114.0, 112.0, 115.0, 114.0, 119.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 137.0, 131.0, 191.0, 129.0, 142.0, 154.0, 132.0, 191.0, 129.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 109.0, 76.0, 77.0, 76.0, 75.0, 99.0, 77.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 118.0, 83.0, 85.0, 84.0, 121.0, 83.0, 84.0, 118.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 87.0, 63.0, 63.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 67.0, 65.0, 67.0, 65.0, 67.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 82.0, 83.0, 88.0, 84.0, 83.0, 83.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 131.0, 129.0, 129.0, 130.0, 127.0, 128.0, 129.0, 179.0, 131.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 172.0, 175.0, 173.0, 174.0, 174.0, 174.0, 171.0, 168.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 67.0, 66.0, 65.0, 67.0, 69.0, 67.0, 68.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1548.9, "alertThreshold": 2.0}]} 16:51:10 INFO - Return code: 0 16:51:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:51:10 ERROR - # TBPL SUCCESS # 16:51:10 INFO - Running post-action listener: _resource_record_post_action 16:51:10 INFO - ##### 16:51:10 INFO - ##### Finished run-tests step (success) 16:51:10 INFO - ##### 16:51:10 INFO - Running post-run listener: _resource_record_post_run 16:51:10 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 521166848; Read time: 16; Write time: 665988 16:51:10 INFO - TinderboxPrint: CPU usage
13.2% 16:51:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:51:10 INFO - TinderboxPrint: I/O write bytes / time
521,166,848 / 665,988 16:51:10 INFO - TinderboxPrint: CPU idle
8,313.1 (86.8%) 16:51:10 INFO - TinderboxPrint: CPU user
1,218.5 (12.7%) 16:51:10 INFO - TinderboxPrint: Swap in / out
0 / 0 16:51:10 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 233189376; Read time: 0; Write time: 274628 16:51:10 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284184576; Read time: 16; Write time: 390808 16:51:10 INFO - Running post-run listener: _upload_blobber_files 16:51:10 INFO - Blob upload gear active. 16:51:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:51:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:51:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:51:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:51:11 INFO - (blobuploader) - INFO - Open directory for files ... 16:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:51:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:17 INFO - (blobuploader) - INFO - Done attempting. 16:51:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:51:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:51:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:18 INFO - (blobuploader) - INFO - Done attempting. 16:51:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:51:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:51:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:18 INFO - (blobuploader) - INFO - Done attempting. 16:51:18 INFO - (blobuploader) - INFO - Iteration through files over. 16:51:18 INFO - Return code: 0 16:51:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:51:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:51:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a52679f46056adff62bdbd299f7ad1ee1157ae7bc94b8113bf17b04c29045f1ccba4959c8540513fa48735d6251a5ece52546e29817e8157e4e8adaf20d49927", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bbf14a41f5ba04936a28a015d82d7eb52ceb85304afab80a410415527b0aa1daccd8ee952803ea8a7c7ec910a24816a4f6f8b17f07a2b12af278d6f50973dc3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/953f405820dda49c04fd51e2c5e034c9dcd27aafb481e72c5a576980ebccaec1e959cbaf171a73e64eba480def4afa0366157ae02d5898d4f45a445c551556ec"} 16:51:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:51:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:51:18 INFO - Contents: 16:51:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a52679f46056adff62bdbd299f7ad1ee1157ae7bc94b8113bf17b04c29045f1ccba4959c8540513fa48735d6251a5ece52546e29817e8157e4e8adaf20d49927", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bbf14a41f5ba04936a28a015d82d7eb52ceb85304afab80a410415527b0aa1daccd8ee952803ea8a7c7ec910a24816a4f6f8b17f07a2b12af278d6f50973dc3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/953f405820dda49c04fd51e2c5e034c9dcd27aafb481e72c5a576980ebccaec1e959cbaf171a73e64eba480def4afa0366157ae02d5898d4f45a445c551556ec"} 16:51:18 INFO - Running post-run listener: copy_logs_to_upload_dir 16:51:18 INFO - Copying logs to upload dir... 16:51:18 INFO - mkdir: /builds/slave/test/build/upload/logs 16:51:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.232096 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-25 16:51:18.746311) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 16:51:18.752122) ========= 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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 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/a52679f46056adff62bdbd299f7ad1ee1157ae7bc94b8113bf17b04c29045f1ccba4959c8540513fa48735d6251a5ece52546e29817e8157e4e8adaf20d49927", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bbf14a41f5ba04936a28a015d82d7eb52ceb85304afab80a410415527b0aa1daccd8ee952803ea8a7c7ec910a24816a4f6f8b17f07a2b12af278d6f50973dc3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/953f405820dda49c04fd51e2c5e034c9dcd27aafb481e72c5a576980ebccaec1e959cbaf171a73e64eba480def4afa0366157ae02d5898d4f45a445c551556ec"} build_url:https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/cGEYCM3pQcKT7msDfMYj4A/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/a52679f46056adff62bdbd299f7ad1ee1157ae7bc94b8113bf17b04c29045f1ccba4959c8540513fa48735d6251a5ece52546e29817e8157e4e8adaf20d49927", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bbf14a41f5ba04936a28a015d82d7eb52ceb85304afab80a410415527b0aa1daccd8ee952803ea8a7c7ec910a24816a4f6f8b17f07a2b12af278d6f50973dc3b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/953f405820dda49c04fd51e2c5e034c9dcd27aafb481e72c5a576980ebccaec1e959cbaf171a73e64eba480def4afa0366157ae02d5898d4f45a445c551556ec"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 16:51:18.777819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:51:18.778150) ========= 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=5bd038846534b2fdfa68eeae000001e2-1464218650.414571-1475223625 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:51:18.865405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 16:51:18.866040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 16:51:18.866788) ========= ========= Total master_lag: 0.18 =========