builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1464828491.2 results: success (0) buildid: 20160601162653 builduid: daaa762bb1e943c0b003167783d2861e revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.203516) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.204396) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.204690) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.231302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.231604) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-01 17:48:11-- 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% 47.0M=0s 2016-06-01 17:48:11 (47.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.328620) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.328921) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.378882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:11.379213) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 22047a4eea784c15026c77911c0bd6ea1b70fa68 --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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-01 17:48:11,544 truncating revision to first 12 chars 2016-06-01 17:48:11,544 Setting DEBUG logging. 2016-06-01 17:48:11,544 attempt 1/10 2016-06-01 17:48:11,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/22047a4eea78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 17:48:11,850 unpacking tar archive at: mozilla-central-22047a4eea78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632383 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:12.025115) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:12.025421) ========= script_repo_revision: 22047a4eea784c15026c77911c0bd6ea1b70fa68 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:12.025896) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:12.026163) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 17:48:12.039254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-01 17:48:12.039551) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:48:12 INFO - MultiFileLogger online at 20160601 17:48:12 in /builds/slave/test 17:48:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:48:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:48:12 INFO - {'append_to_log': False, 17:48:12 INFO - 'base_work_dir': '/builds/slave/test', 17:48:12 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:48:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:48:12 INFO - 'branch': 'Firefox-Non-PGO', 17:48:12 INFO - 'buildbot_json_path': 'buildprops.json', 17:48:12 INFO - 'config_files': ('talos/linux_config.py',), 17:48:12 INFO - 'default_actions': ('clobber', 17:48:12 INFO - 'read-buildbot-config', 17:48:12 INFO - 'download-and-extract', 17:48:12 INFO - 'populate-webroot', 17:48:12 INFO - 'create-virtualenv', 17:48:12 INFO - 'install', 17:48:12 INFO - 'run-tests'), 17:48:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:48:12 INFO - 'download_minidump_stackwalk': True, 17:48:12 INFO - 'download_symbols': 'ondemand', 17:48:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:48:12 INFO - 'tooltool.py': '/tools/tooltool.py', 17:48:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:48:12 INFO - '/tools/misc-python/virtualenv.py')}, 17:48:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:48:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:48:12 INFO - 'installer_path': 'installer.exe', 17:48:12 INFO - 'log_level': 'info', 17:48:12 INFO - 'log_name': 'talos', 17:48:12 INFO - 'log_to_console': True, 17:48:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:48:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:48:12 INFO - 'opt_config_files': (), 17:48:12 INFO - 'pip_index': False, 17:48:12 INFO - 'sps_profile': False, 17:48:12 INFO - 'sps_profile_interval': 0, 17:48:12 INFO - 'suite': 'dromaeojs', 17:48:12 INFO - 'system_bits': '32', 17:48:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:48:12 INFO - 'title': 'talos-linux64-ix-030', 17:48:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:48:12 INFO - 'use_talos_json': True, 17:48:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:48:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:48:12 INFO - 'work_dir': 'build'} 17:48:12 INFO - ##### 17:48:12 INFO - ##### Running clobber step. 17:48:12 INFO - ##### 17:48:12 INFO - Running pre-action listener: _resource_record_pre_action 17:48:12 INFO - Running main action method: clobber 17:48:12 INFO - rmtree: /builds/slave/test/build 17:48:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:48:12 INFO - Running post-action listener: _resource_record_post_action 17:48:12 INFO - ##### 17:48:12 INFO - ##### Finished clobber step (success) 17:48:12 INFO - ##### 17:48:12 INFO - ##### 17:48:12 INFO - ##### Running read-buildbot-config step. 17:48:12 INFO - ##### 17:48:12 INFO - Running pre-action listener: _resource_record_pre_action 17:48:12 INFO - Running main action method: read_buildbot_config 17:48:12 INFO - Using buildbot properties: 17:48:12 INFO - { 17:48:12 INFO - "project": "", 17:48:12 INFO - "product": "firefox", 17:48:12 INFO - "script_repo_revision": "production", 17:48:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 17:48:12 INFO - "repository": "", 17:48:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 17:48:12 INFO - "buildid": "20160601162653", 17:48:12 INFO - "pgo_build": "False", 17:48:12 INFO - "basedir": "/builds/slave/test", 17:48:12 INFO - "buildnumber": 517, 17:48:12 INFO - "slavename": "talos-linux64-ix-030", 17:48:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:48:12 INFO - "platform": "ubuntu64_hw", 17:48:12 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 17:48:12 INFO - "branch": "mozilla-central", 17:48:12 INFO - "repo_path": "mozilla-central", 17:48:12 INFO - "revision": "22047a4eea784c15026c77911c0bd6ea1b70fa68", 17:48:12 INFO - "stage_platform": "linux64", 17:48:12 INFO - "builduid": "daaa762bb1e943c0b003167783d2861e", 17:48:12 INFO - "slavebuilddir": "test" 17:48:12 INFO - } 17:48:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:48:12 INFO - Running post-action listener: _resource_record_post_action 17:48:12 INFO - ##### 17:48:12 INFO - ##### Finished read-buildbot-config step (success) 17:48:12 INFO - ##### 17:48:12 INFO - ##### 17:48:12 INFO - ##### Running download-and-extract step. 17:48:12 INFO - ##### 17:48:12 INFO - Running pre-action listener: _resource_record_pre_action 17:48:12 INFO - Running main action method: download_and_extract 17:48:12 INFO - mkdir: /builds/slave/test/build/tests 17:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:12 INFO - https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:48:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:48:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:48:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:48:18 INFO - Downloaded 1361 bytes. 17:48:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:48:18 INFO - Using the following test package requirements: 17:48:18 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:48:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:48:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:48:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'jsshell-linux-x86_64.zip'], 17:48:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:48:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:48:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:48:18 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:48:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:48:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:48:18 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:48:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:18 INFO - https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:48:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:48:23 INFO - Downloaded 22849819 bytes. 17:48:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:48:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:48:24 INFO - Return code: 0 17:48:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:24 INFO - https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:48:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:48:27 INFO - Downloaded 22849819 bytes. 17:48:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:48:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:48:29 INFO - Return code: 0 17:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:29 INFO - https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:48:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:48:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:48:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:48:30 INFO - Downloaded 10834055 bytes. 17:48:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:48:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:48:30 INFO - Return code: 0 17:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:30 INFO - https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:48:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:48:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:48:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:48:33 INFO - Downloaded 55062709 bytes. 17:48:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:48:33 INFO - mkdir: /builds/slave/test/properties 17:48:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:48:33 INFO - Writing to file /builds/slave/test/properties/build_url 17:48:33 INFO - Contents: 17:48:33 INFO - build_url:https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:48:34 INFO - Running post-action listener: _resource_record_post_action 17:48:34 INFO - Running post-action listener: set_extra_try_arguments 17:48:34 INFO - ##### 17:48:34 INFO - ##### Finished download-and-extract step (success) 17:48:34 INFO - ##### 17:48:34 INFO - ##### 17:48:34 INFO - ##### Running populate-webroot step. 17:48:34 INFO - ##### 17:48:34 INFO - Running pre-action listener: _resource_record_pre_action 17:48:34 INFO - Running main action method: populate_webroot 17:48:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:48:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:48:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:48:34 INFO - '--tppagecycles', 17:48:34 INFO - '7'], 17:48:34 INFO - 'tests': ['tsvgm']}}, 17:48:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:48:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:48:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:48:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:48:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:48:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:48:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['damp', 'tps']}, 17:48:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'tests': ['damp', 'tps']}, 17:48:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['dromaeo_dom']}, 17:48:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:48:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'other-e10s': {'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['a11yr', 17:48:34 INFO - 'ts_paint', 17:48:34 INFO - 'tpaint', 17:48:34 INFO - 'sessionrestore', 17:48:34 INFO - 'sessionrestore_no_auto_restore', 17:48:34 INFO - 'tabpaint']}, 17:48:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['tsvgx', 17:48:34 INFO - 'tsvgr_opacity', 17:48:34 INFO - 'tart', 17:48:34 INFO - 'tscrollx', 17:48:34 INFO - 'cart']}, 17:48:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:48:34 INFO - 'tsvgr_opacity', 17:48:34 INFO - 'tart', 17:48:34 INFO - 'tscrollx', 17:48:34 INFO - 'cart']}, 17:48:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'talos_options': ['--disable-e10s'], 17:48:34 INFO - 'tests': ['tp5o']}, 17:48:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'tests': ['tp5o']}, 17:48:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'talos_options': ['--disable-e10s', 17:48:34 INFO - '--xperf_path', 17:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:48:34 INFO - 'tests': ['tp5n']}, 17:48:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:48:34 INFO - 'talos_options': ['--xperf_path', 17:48:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:48:34 INFO - 'tests': ['tp5n']}}, 17:48:34 INFO - 'talos.zip': {'path': '', 17:48:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:48:34 INFO - Running post-action listener: _resource_record_post_action 17:48:34 INFO - ##### 17:48:34 INFO - ##### Finished populate-webroot step (success) 17:48:34 INFO - ##### 17:48:34 INFO - ##### 17:48:34 INFO - ##### Running create-virtualenv step. 17:48:34 INFO - ##### 17:48:34 INFO - Running pre-action listener: _resource_record_pre_action 17:48:34 INFO - Running main action method: create_virtualenv 17:48:34 INFO - Creating virtualenv /builds/slave/test/build/venv 17:48:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:48:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:48:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:48:34 INFO - Using real prefix '/usr' 17:48:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:48:35 INFO - Installing distribute.............................................................................................................................................................................................done. 17:48:36 INFO - Installing pip.................done. 17:48:36 INFO - Return code: 0 17:48:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:36 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:48:36 INFO - Using env: {'DISPLAY': ':0', 17:48:36 INFO - 'HOME': '/home/cltbld', 17:48:36 INFO - 'LANG': 'en_US.UTF-8', 17:48:36 INFO - 'LANGUAGE': 'en_US:en', 17:48:36 INFO - 'LOGNAME': 'cltbld', 17:48:36 INFO - 'MAIL': '/var/mail/cltbld', 17:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:36 INFO - 'MOZ_NO_REMOTE': '1', 17:48:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:36 INFO - 'NO_EM_RESTART': '1', 17:48:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:37 INFO - 'PWD': '/builds/slave/test', 17:48:37 INFO - 'SHELL': '/bin/bash', 17:48:37 INFO - 'SHLVL': '1', 17:48:37 INFO - 'TERM': 'linux', 17:48:37 INFO - 'TMOUT': '86400', 17:48:37 INFO - 'USER': 'cltbld', 17:48:37 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:37 INFO - '_': '/tools/buildbot/bin/python'} 17:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:48:37 INFO - Cleaning up... 17:48:37 INFO - Return code: 0 17:48:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:37 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:48:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:48:37 INFO - Using env: {'DISPLAY': ':0', 17:48:37 INFO - 'HOME': '/home/cltbld', 17:48:37 INFO - 'LANG': 'en_US.UTF-8', 17:48:37 INFO - 'LANGUAGE': 'en_US:en', 17:48:37 INFO - 'LOGNAME': 'cltbld', 17:48:37 INFO - 'MAIL': '/var/mail/cltbld', 17:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:37 INFO - 'MOZ_NO_REMOTE': '1', 17:48:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:37 INFO - 'NO_EM_RESTART': '1', 17:48:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:37 INFO - 'PWD': '/builds/slave/test', 17:48:37 INFO - 'SHELL': '/bin/bash', 17:48:37 INFO - 'SHLVL': '1', 17:48:37 INFO - 'TERM': 'linux', 17:48:37 INFO - 'TMOUT': '86400', 17:48:37 INFO - 'USER': 'cltbld', 17:48:37 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:37 INFO - '_': '/tools/buildbot/bin/python'} 17:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:37 INFO - Downloading/unpacking psutil>=3.1.1 17:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:48:39 INFO - Installing collected packages: psutil 17:48:39 INFO - Running setup.py install for psutil 17:48:39 INFO - building 'psutil._psutil_linux' extension 17:48:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:48:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:48:39 INFO - building 'psutil._psutil_posix' extension 17:48:39 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:48:39 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:48:39 INFO - Successfully installed psutil 17:48:39 INFO - Cleaning up... 17:48:40 INFO - Return code: 0 17:48:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:40 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:48:40 INFO - Using env: {'DISPLAY': ':0', 17:48:40 INFO - 'HOME': '/home/cltbld', 17:48:40 INFO - 'LANG': 'en_US.UTF-8', 17:48:40 INFO - 'LANGUAGE': 'en_US:en', 17:48:40 INFO - 'LOGNAME': 'cltbld', 17:48:40 INFO - 'MAIL': '/var/mail/cltbld', 17:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:40 INFO - 'MOZ_NO_REMOTE': '1', 17:48:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:40 INFO - 'NO_EM_RESTART': '1', 17:48:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:40 INFO - 'PWD': '/builds/slave/test', 17:48:40 INFO - 'SHELL': '/bin/bash', 17:48:40 INFO - 'SHLVL': '1', 17:48:40 INFO - 'TERM': 'linux', 17:48:40 INFO - 'TMOUT': '86400', 17:48:40 INFO - 'USER': 'cltbld', 17:48:40 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:40 INFO - '_': '/tools/buildbot/bin/python'} 17:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:48:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:48:42 INFO - Installing collected packages: mozsystemmonitor 17:48:42 INFO - Running setup.py install for mozsystemmonitor 17:48:42 INFO - Successfully installed mozsystemmonitor 17:48:42 INFO - Cleaning up... 17:48:42 INFO - Return code: 0 17:48:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48: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')]} 17:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:48:42 INFO - Using env: {'DISPLAY': ':0', 17:48:42 INFO - 'HOME': '/home/cltbld', 17:48:42 INFO - 'LANG': 'en_US.UTF-8', 17:48:42 INFO - 'LANGUAGE': 'en_US:en', 17:48:42 INFO - 'LOGNAME': 'cltbld', 17:48:42 INFO - 'MAIL': '/var/mail/cltbld', 17:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:42 INFO - 'MOZ_NO_REMOTE': '1', 17:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:42 INFO - 'NO_EM_RESTART': '1', 17:48:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:42 INFO - 'PWD': '/builds/slave/test', 17:48:42 INFO - 'SHELL': '/bin/bash', 17:48:42 INFO - 'SHLVL': '1', 17:48:42 INFO - 'TERM': 'linux', 17:48:42 INFO - 'TMOUT': '86400', 17:48:42 INFO - 'USER': 'cltbld', 17:48:42 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:42 INFO - '_': '/tools/buildbot/bin/python'} 17:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:42 INFO - Downloading/unpacking blobuploader==1.2.4 17:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:44 INFO - Downloading blobuploader-1.2.4.tar.gz 17:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:48:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:48:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:45 INFO - Downloading docopt-0.6.1.tar.gz 17:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:48:45 INFO - Installing collected packages: blobuploader, requests, docopt 17:48:45 INFO - Running setup.py install for blobuploader 17:48:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:48:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:48:45 INFO - Running setup.py install for requests 17:48:45 INFO - Running setup.py install for docopt 17:48:45 INFO - Successfully installed blobuploader requests docopt 17:48:45 INFO - Cleaning up... 17:48:45 INFO - Return code: 0 17:48:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:45 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:48:45 INFO - Using env: {'DISPLAY': ':0', 17:48:45 INFO - 'HOME': '/home/cltbld', 17:48:45 INFO - 'LANG': 'en_US.UTF-8', 17:48:45 INFO - 'LANGUAGE': 'en_US:en', 17:48:45 INFO - 'LOGNAME': 'cltbld', 17:48:45 INFO - 'MAIL': '/var/mail/cltbld', 17:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:45 INFO - 'MOZ_NO_REMOTE': '1', 17:48:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:45 INFO - 'NO_EM_RESTART': '1', 17:48:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:45 INFO - 'PWD': '/builds/slave/test', 17:48:45 INFO - 'SHELL': '/bin/bash', 17:48:45 INFO - 'SHLVL': '1', 17:48:45 INFO - 'TERM': 'linux', 17:48:45 INFO - 'TMOUT': '86400', 17:48:45 INFO - 'USER': 'cltbld', 17:48:45 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:45 INFO - '_': '/tools/buildbot/bin/python'} 17:48:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:48:45 INFO - Running setup.py (path:/tmp/pip-25qtCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:48:46 INFO - Running setup.py (path:/tmp/pip-jjILB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:48:46 INFO - Running setup.py (path:/tmp/pip-9pzX6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:48:46 INFO - Running setup.py (path:/tmp/pip-EfQJ_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:48:46 INFO - Running setup.py (path:/tmp/pip-w8A583-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:48:46 INFO - Running setup.py (path:/tmp/pip-fEkGqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:48:46 INFO - Running setup.py (path:/tmp/pip-eVMwx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:48:46 INFO - Running setup.py (path:/tmp/pip-TM7WlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:48:46 INFO - Running setup.py (path:/tmp/pip-VS2pEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:48:46 INFO - Running setup.py (path:/tmp/pip-OZHuIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:48:46 INFO - Running setup.py (path:/tmp/pip-EVR7hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:48:46 INFO - Running setup.py (path:/tmp/pip-lsmvr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:48:46 INFO - Running setup.py (path:/tmp/pip-bsJgkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:48:46 INFO - Running setup.py (path:/tmp/pip-NRzrt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:46 INFO - Running setup.py (path:/tmp/pip-4Mqd5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:48:47 INFO - Running setup.py (path:/tmp/pip-SfUwGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:48:47 INFO - Running setup.py (path:/tmp/pip-Azb3v5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:48:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:48:47 INFO - Running setup.py install for manifestparser 17:48:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Running setup.py install for mozcrash 17:48:47 INFO - Running setup.py install for mozdebug 17:48:47 INFO - Running setup.py install for mozdevice 17:48:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Running setup.py install for mozfile 17:48:47 INFO - Running setup.py install for mozhttpd 17:48:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Running setup.py install for mozinfo 17:48:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Running setup.py install for mozInstall 17:48:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:48:47 INFO - Running setup.py install for mozleak 17:48:48 INFO - Running setup.py install for mozlog 17:48:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Running setup.py install for moznetwork 17:48:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Running setup.py install for mozprocess 17:48:48 INFO - Running setup.py install for mozprofile 17:48:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Running setup.py install for mozrunner 17:48:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Running setup.py install for mozscreenshot 17:48:48 INFO - Running setup.py install for moztest 17:48:48 INFO - Running setup.py install for mozversion 17:48:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:48:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:48:48 INFO - Cleaning up... 17:48:48 INFO - Return code: 0 17:48:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:48 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:48:48 INFO - Using env: {'DISPLAY': ':0', 17:48:48 INFO - 'HOME': '/home/cltbld', 17:48:48 INFO - 'LANG': 'en_US.UTF-8', 17:48:48 INFO - 'LANGUAGE': 'en_US:en', 17:48:48 INFO - 'LOGNAME': 'cltbld', 17:48:48 INFO - 'MAIL': '/var/mail/cltbld', 17:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:48 INFO - 'MOZ_NO_REMOTE': '1', 17:48:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:48 INFO - 'NO_EM_RESTART': '1', 17:48:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:48 INFO - 'PWD': '/builds/slave/test', 17:48:48 INFO - 'SHELL': '/bin/bash', 17:48:48 INFO - 'SHLVL': '1', 17:48:48 INFO - 'TERM': 'linux', 17:48:48 INFO - 'TMOUT': '86400', 17:48:48 INFO - 'USER': 'cltbld', 17:48:48 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:48 INFO - '_': '/tools/buildbot/bin/python'} 17:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:48:49 INFO - Running setup.py (path:/tmp/pip-GvS59c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:48:49 INFO - Running setup.py (path:/tmp/pip-K9Udwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:48:49 INFO - Running setup.py (path:/tmp/pip-DJ7fSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:48:49 INFO - Running setup.py (path:/tmp/pip-WGu69L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:48:49 INFO - Running setup.py (path:/tmp/pip-zGhsQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:48:49 INFO - Running setup.py (path:/tmp/pip-dXuKik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:48:49 INFO - Running setup.py (path:/tmp/pip-wu_qQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:48:49 INFO - Running setup.py (path:/tmp/pip-Ax35Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:48:49 INFO - Running setup.py (path:/tmp/pip-GWgLcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:48:49 INFO - Running setup.py (path:/tmp/pip-5ewDdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:48:49 INFO - Running setup.py (path:/tmp/pip-wq2DOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:48:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:48:49 INFO - Running setup.py (path:/tmp/pip-kGBLyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:48:50 INFO - Running setup.py (path:/tmp/pip-yOjqpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:48:50 INFO - Running setup.py (path:/tmp/pip-BxSUDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:50 INFO - Running setup.py (path:/tmp/pip-68VCnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:48:50 INFO - Running setup.py (path:/tmp/pip-Fg2Xe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:48:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:48:50 INFO - Running setup.py (path:/tmp/pip-Y4UuU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:48:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:48:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:52 INFO - Downloading blessings-1.6.tar.gz 17:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:48:52 INFO - Installing collected packages: blessings 17:48:52 INFO - Running setup.py install for blessings 17:48:52 INFO - Successfully installed blessings 17:48:52 INFO - Cleaning up... 17:48:52 INFO - Return code: 0 17:48:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:52 INFO - Reading from file tmpfile_stdout 17:48:52 INFO - Current package versions: 17:48:52 INFO - argparse == 1.2.1 17:48:52 INFO - blessings == 1.6 17:48:52 INFO - blobuploader == 1.2.4 17:48:52 INFO - docopt == 0.6.1 17:48:52 INFO - manifestparser == 1.1 17:48:52 INFO - mozInstall == 1.12 17:48:52 INFO - mozcrash == 0.17 17:48:52 INFO - mozdebug == 0.1 17:48:52 INFO - mozdevice == 0.48 17:48:52 INFO - mozfile == 1.2 17:48:52 INFO - mozhttpd == 0.7 17:48:52 INFO - mozinfo == 0.9 17:48:52 INFO - mozleak == 0.1 17:48:52 INFO - mozlog == 3.2 17:48:52 INFO - moznetwork == 0.27 17:48:52 INFO - mozprocess == 0.22 17:48:52 INFO - mozprofile == 0.28 17:48:52 INFO - mozrunner == 6.11 17:48:52 INFO - mozscreenshot == 0.1 17:48:52 INFO - mozsystemmonitor == 0.3 17:48:52 INFO - moztest == 0.7 17:48:52 INFO - mozversion == 1.4 17:48:52 INFO - psutil == 3.1.1 17:48:52 INFO - requests == 1.2.3 17:48:52 INFO - wsgiref == 0.1.2 17:48:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:52 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 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:48:52 INFO - Using env: {'DISPLAY': ':0', 17:48:52 INFO - 'HOME': '/home/cltbld', 17:48:52 INFO - 'LANG': 'en_US.UTF-8', 17:48:52 INFO - 'LANGUAGE': 'en_US:en', 17:48:52 INFO - 'LOGNAME': 'cltbld', 17:48:52 INFO - 'MAIL': '/var/mail/cltbld', 17:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:52 INFO - 'MOZ_NO_REMOTE': '1', 17:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:52 INFO - 'NO_EM_RESTART': '1', 17:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:52 INFO - 'PWD': '/builds/slave/test', 17:48:52 INFO - 'SHELL': '/bin/bash', 17:48:52 INFO - 'SHLVL': '1', 17:48:52 INFO - 'TERM': 'linux', 17:48:52 INFO - 'TMOUT': '86400', 17:48:52 INFO - 'USER': 'cltbld', 17:48:52 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:52 INFO - '_': '/tools/buildbot/bin/python'} 17:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:48:52 INFO - Cleaning up... 17:48:52 INFO - Return code: 0 17:48:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:48:52 INFO - Using env: {'DISPLAY': ':0', 17:48:52 INFO - 'HOME': '/home/cltbld', 17:48:52 INFO - 'LANG': 'en_US.UTF-8', 17:48:52 INFO - 'LANGUAGE': 'en_US:en', 17:48:52 INFO - 'LOGNAME': 'cltbld', 17:48:52 INFO - 'MAIL': '/var/mail/cltbld', 17:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:52 INFO - 'MOZ_NO_REMOTE': '1', 17:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:52 INFO - 'NO_EM_RESTART': '1', 17:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:52 INFO - 'PWD': '/builds/slave/test', 17:48:52 INFO - 'SHELL': '/bin/bash', 17:48:52 INFO - 'SHLVL': '1', 17:48:52 INFO - 'TERM': 'linux', 17:48:52 INFO - 'TMOUT': '86400', 17:48:52 INFO - 'USER': 'cltbld', 17:48:52 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:52 INFO - '_': '/tools/buildbot/bin/python'} 17:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:53 INFO - Downloading/unpacking jsonschema 17:48: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 17:48: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 17:48: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 17:48: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 17:48: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 17:48: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 17:48:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:48:55 INFO - Downloading/unpacking functools32 (from jsonschema) 17:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:55 INFO - Downloading functools32-3.2.3-2.tar.gz 17:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:48:55 INFO - warning: no files found matching '*.txt' 17:48:55 INFO - no previously-included directories found matching 'build' 17:48:55 INFO - no previously-included directories found matching 'dist' 17:48:55 INFO - no previously-included directories found matching '.git*' 17:48:55 INFO - Installing collected packages: jsonschema, functools32 17:48:55 INFO - Running setup.py install for functools32 17:48:55 INFO - warning: no files found matching '*.txt' 17:48:55 INFO - no previously-included directories found matching 'build' 17:48:55 INFO - no previously-included directories found matching 'dist' 17:48:55 INFO - no previously-included directories found matching '.git*' 17:48:55 INFO - Successfully installed jsonschema functools32 17:48:55 INFO - Cleaning up... 17:48:55 INFO - Return code: 0 17:48:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:48:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b18bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15308a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15fb770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:48:55 INFO - Using env: {'DISPLAY': ':0', 17:48:55 INFO - 'HOME': '/home/cltbld', 17:48:55 INFO - 'LANG': 'en_US.UTF-8', 17:48:55 INFO - 'LANGUAGE': 'en_US:en', 17:48:55 INFO - 'LOGNAME': 'cltbld', 17:48:55 INFO - 'MAIL': '/var/mail/cltbld', 17:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:55 INFO - 'MOZ_NO_REMOTE': '1', 17:48:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:55 INFO - 'NO_EM_RESTART': '1', 17:48:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:55 INFO - 'PWD': '/builds/slave/test', 17:48:55 INFO - 'SHELL': '/bin/bash', 17:48:55 INFO - 'SHLVL': '1', 17:48:55 INFO - 'TERM': 'linux', 17:48:55 INFO - 'TMOUT': '86400', 17:48:55 INFO - 'USER': 'cltbld', 17:48:55 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:55 INFO - '_': '/tools/buildbot/bin/python'} 17:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:55 INFO - Downloading/unpacking flake8 17:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:48:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:48:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:48:58 INFO - no previously-included directories found matching 'docs/_build' 17:48:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:48:58 INFO - Running setup.py install for pep8 17:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:48:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:48:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:48:58 INFO - no previously-included directories found matching 'docs/_build' 17:48:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:48:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:48:58 INFO - Cleaning up... 17:48:58 INFO - Return code: 0 17:48:58 INFO - Running post-action listener: _resource_record_post_action 17:48:58 INFO - Running post-action listener: _start_resource_monitoring 17:48:58 INFO - Starting resource monitoring. 17:48:58 INFO - ##### 17:48:58 INFO - ##### Finished create-virtualenv step (success) 17:48:58 INFO - ##### 17:48:58 INFO - ##### 17:48:58 INFO - ##### Running install step. 17:48:58 INFO - ##### 17:48:58 INFO - Running pre-action listener: _resource_record_pre_action 17:48:58 INFO - Running main action method: install 17:48:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:58 INFO - Reading from file tmpfile_stdout 17:48:58 INFO - Detecting whether we're running mozinstall >=1.0... 17:48:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:48:58 INFO - Reading from file tmpfile_stdout 17:48:58 INFO - Output received: 17:48:58 INFO - Usage: mozinstall [options] installer 17:48:58 INFO - Options: 17:48:58 INFO - -h, --help show this help message and exit 17:48:58 INFO - -d DEST, --destination=DEST 17:48:58 INFO - Directory to install application into. [default: 17:48:58 INFO - "/builds/slave/test"] 17:48:58 INFO - --app=APP Application being installed. [default: firefox] 17:48:58 INFO - mkdir: /builds/slave/test/build/application 17:48:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:48:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:49:14 INFO - Reading from file tmpfile_stdout 17:49:14 INFO - Output received: 17:49:14 INFO - /builds/slave/test/build/application/firefox/firefox 17:49:14 INFO - Running post-action listener: _resource_record_post_action 17:49:14 INFO - ##### 17:49:14 INFO - ##### Finished install step (success) 17:49:14 INFO - ##### 17:49:14 INFO - ##### 17:49:14 INFO - ##### Running run-tests step. 17:49:14 INFO - ##### 17:49:14 INFO - Running pre-action listener: _resource_record_pre_action 17:49:14 INFO - Running main action method: run_tests 17:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:49:14 INFO - Python 2.7.3 17:49:14 INFO - Return code: 0 17:49:14 INFO - grabbing minidump binary from tooltool 17:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:49:14 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 0x168a9c0>, '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 0x168b8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x168bd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:49:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:49:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:49:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:49:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:49:14 INFO - Return code: 0 17:49:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:49:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:49:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:49:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:49:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:49:15 INFO - Return code: 0 17:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:49:15 INFO - Using env: {'DISPLAY': ':0', 17:49:15 INFO - 'HOME': '/home/cltbld', 17:49:15 INFO - 'LANG': 'en_US.UTF-8', 17:49:15 INFO - 'LANGUAGE': 'en_US:en', 17:49:15 INFO - 'LOGNAME': 'cltbld', 17:49:15 INFO - 'MAIL': '/var/mail/cltbld', 17:49:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:49:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:15 INFO - 'MOZ_NO_REMOTE': '1', 17:49:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:49:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:15 INFO - 'NO_EM_RESTART': '1', 17:49:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:15 INFO - 'PWD': '/builds/slave/test', 17:49:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:49:15 INFO - 'SHELL': '/bin/bash', 17:49:15 INFO - 'SHLVL': '1', 17:49:15 INFO - 'TERM': 'linux', 17:49:15 INFO - 'TMOUT': '86400', 17:49:15 INFO - 'USER': 'cltbld', 17:49:15 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719', 17:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:15 INFO - '_': '/tools/buildbot/bin/python'} 17:49:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:49:16 INFO - mozversion application_buildid: 20160601162653 17:49:16 INFO - mozversion application_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 17:49:16 INFO - mozversion application_display_name: Nightly 17:49:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:49:16 INFO - mozversion application_name: Firefox 17:49:16 INFO - mozversion application_remotingname: firefox 17:49:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:49:16 INFO - mozversion application_vendor: Mozilla 17:49:16 INFO - mozversion application_version: 49.0a1 17:49:16 INFO - mozversion platform_buildid: 20160601162653 17:49:16 INFO - mozversion platform_changeset: 22047a4eea784c15026c77911c0bd6ea1b70fa68 17:49:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:49:16 INFO - mozversion platform_version: 49.0a1 17:49:16 INFO - using testdate: 1464828556 17:49:16 INFO - actual date: 1464828556 17:49:16 INFO - starting webserver on 'localhost:47143' 17:49:16 INFO - SUITE-START | Running 2 tests 17:49:16 INFO - TEST-START | dromaeo_css 17:49:16 INFO - operating with platform_type : linux_ 17:49:16 INFO - Initialising browser for dromaeo_css test... 17:49:16 INFO - TEST-INFO | started process 7129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6rfU2I/profile http://localhost:47143/getInfo.html) 17:49:20 INFO - PROCESS | 7129 | __metrics Screen width/height:1600/1200 17:49:20 INFO - PROCESS | 7129 | colorDepth:24 17:49:20 INFO - PROCESS | 7129 | Browser inner width/height: 1024/697 17:49:20 INFO - PROCESS | 7129 | __metrics 17:49:21 ERROR - PROCESS | 7129 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:49:22 INFO - TEST-INFO | 7129: exit 0 17:49:22 INFO - Browser initialized. 17:49:22 INFO - Running cycle 1/1 for dromaeo_css test... 17:49:22 INFO - TEST-INFO | started process 7209 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6rfU2I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:34 INFO - PROCESS | 7209 | RSS: Main: 157163520 17:49:34 INFO - PROCESS | 7209 | 17:50:25 INFO - PROCESS | 7209 | 1464828625619 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:52:37 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47143/tests/dromaeo/cssquery-ext.html) 17:52:38 INFO - PROCESS | 7209 | RSS: Main: 233914368 17:52:38 INFO - PROCESS | 7209 | 17:55:40 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-ext.html (next: http://localhost:47143/tests/dromaeo/cssquery-jquery.html) 17:55:41 INFO - PROCESS | 7209 | RSS: Main: 239771648 17:55:41 INFO - PROCESS | 7209 | 17:58:43 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47143/tests/dromaeo/cssquery-mootools.html) 17:58:44 INFO - PROCESS | 7209 | RSS: Main: 245899264 17:58:44 INFO - PROCESS | 7209 | 18:01:46 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47143/tests/dromaeo/cssquery-prototype.html) 18:01:46 INFO - PROCESS | 7209 | RSS: Main: 235737088 18:01:46 INFO - PROCESS | 7209 | 18:04:49 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47143/tests/dromaeo/cssquery-yui.html) 18:04:50 INFO - PROCESS | 7209 | RSS: Main: 226627584 18:04:50 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | Cycle 1(1): loaded http://localhost:47143/tests/dromaeo/cssquery-yui.html (next: http://localhost:47143/tests/dromaeo/cssquery-dojo.html) 18:07:53 INFO - PROCESS | 7209 | RSS: Main: 259108864 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | __start_tp_report 18:07:53 INFO - PROCESS | 7209 | _x_x_mozilla_page_load 18:07:53 INFO - PROCESS | 7209 | _x_x_mozilla_page_load_details 18:07:53 INFO - PROCESS | 7209 | |i|pagename|runs| 18:07:53 INFO - PROCESS | 7209 | |0;dojo.html;2628.3034995726125;2631.88156532956;2660.614210939413;2677.223605154505;2694.3533551947526;4472.686911916167;4567.6574256930735;4577.725336479813;4647.907041859165;4669.206234940059;4553.817847286105;4571.040081582863;4583.862484125473;4609.516000819916;4619.861404157872;4009.3985902114623;4167.541570427251;4234.343676730096;4258.467691538558;4380.737155770664;4264.147170565884;4333.653307735373;4335.739855608658;4343.305071188611;4388.846390376338;4142.275101857163;4179.958200418017;4199.139176468817;4201.76890271035;4203.138356636882;4233.449651545279;4241.27898257297;4246.44796176495;4299.032717638537;4309.396684464146;4275.615194632467;4287.742735435852;4291.849785257532;4330.220560299145;4337.891552711208;4361.062371590124;4456.9748957739575;4488.618467430272;4528.298113792367;4538.500764915857;4998.900021999576;5029.723365214912;5054.7472626368535;5055.848324550269;5067.163917953533;5062.898742025139;5066.442691303953;5086.54221120101;5087.669301495391;5094.949050509522;4486.55134486552;4619.69971951822;4642.721436713808;4645.512221216791;4665.486796452387;5118.692878427306;5134.691918484903;5139.049275883899;5143.3570803649545;5152.716600587004;4809.86968062505;4894.388201474816;4902.950970490249;4940.975295123501;4953.430355509163;4894.167991441393;4930.211166213388;4946.554810067111;4955.628327875424;4957.355543779286;4879.780409881564;4900.215965445512;4901.852944411673;4923.286123512071;4974.651774375759;4777.068471648065;4854.80580776765;4894.632902532324;4922.286268491121;4965.131102057126;4225.176090662353;4242.321228603409;4246.40550322955;4265.97867010663;4284.892877678083;3694.408894576855;3712.406015037635;3715.981420092882;3745.9438108428394;3762.3227818992846;4214.199302132585;4249.277622804131;4291.442112525414;4303.698741088157;4342.978285108554;4936.90126197474;4941.184704523714;4991.625628077909;4993.226049962262;4996.525330093708;4293.635041021548;4300.741955482681;4301.118270754488;4310.051788606502;4353.521112677603;4346.043870348518;4444.31113177458;4474.373587697725;4503.954960450354;4510.075434535912;4707.152712511781;4708.552687494751;4709.858704238878;4730.030343779448;4761.76191190446;4984.6261530384645;4985.850424487271;4986.15235409974;4986.501349864984;5043.394792624908;4545.636349091998;4676.69601475903;4692.741899195577;4698.060387922497;4714.080754252887;4911.582515486224;4929.531694489017;4930.383702037246;4936.851894443173;4985.127602669446;4957.058158949788;5007.223880298559;5008.77460514285;5011.799528018839;5064.265681476091;4649.023705021983;4804.6876952997945;4808.2547205183255;4832.661713680009;4927.039227350632;4836.782344794563;4855.907420830426;4858.781354839111;4861.975690121527;4905.264210368473;4796.112719146969;4799;4805.663603547718;4848.7090774553635;4854.247591623304;4785.59322457581;4860;4905.435874874435;4906.877328066684;4928.383952005999;4773.9761301193275;4821.783019764049;4829.13075646387;4839.491853355347;4894.755262236803 18:07:53 INFO - PROCESS | 7209 | |1;ext.html;544.1192503245885;587.6092398554961;593.0748033068417;596.6360520082751;607.298570151475;28120.156395308124;28706;41036.38445423321;42168.789156054256;42283;38015.42976855349;42228.57771422285;42243.36634950471;42292.36561451581;42325.576744232545;7561.886571701429;7739.071311442634;7743.303102720754;7743.690252389898;7755.1081625613215;7644.885326720104;7741.916131741561;7745.806354841117;7760.650770715331;7773.261540153676;7692.115406728242;7765.689372425096;7766.145723970359;7782.494137881048;7788.06543214815;7726.652300646484;7779;7782.221777822229;7785.416093792988;7786.922130778733;7653.617319134077;7729.497582657109;7745.845083098363;7750.302472777477;7751.379889608817;39314.21371572553;39784.602153978376;39792.20415591701;39804.800975995226;39824;3206.422843888099;3235.7896736712037;3277.2462333663393;3362.1090411041096;3555.8933232003083;29108;29582.556261656093;29833.850830745923;29949.85025074861;29950;3564.108972756811;3564.821758912044;3565.554305711786;3595.496630471736;3604.2250916052913;249683;249761;250426;250846;251390;54194.45805541973;54251.45748542543;54591;54605.45394546083;54651;33418.16454588606;34803.651963480545;34804.303913921576;36149.45775813365;36162.81918590443;15126;15245.771313430307;15264.084154950739;15278.236088195546;15279.312430940634;10849.566017359375;10864;10903.400312982703;10909.781804364027;10916.56333746657;18550.907245463957;18627.90686046561;18676.533086672942;18697.626047478974;18698.252069917322;6866.965665171642;6888.276730943179;7004.334588214111;7008.334208250208;7063.71745130189;841.2807049972185;907.3195103672246;948.2414068745114;996.6412091647002;1040.1314902056868;27590.753142463684;27892.581611275848;28061.719382806317;28209.576856347172;28646;793.730131755206;969.5976169889417;991.5290237137447;1060.3425875956957;1116.1182665694173;14609.123452592878;14679.706405871822;14727;14776.187309698069;14987.250637468083;386503;388430;388455;389266;389994;50409.74795126001;50877.49122508727;50922.23616645753;50926.74536627293;51091.233631495554;1094.7755710079414;1177.3995262416229;1200.0759415250209;1237.6101528018646;1237.7524495101015;28183.859080704464;29310.853445732635;29599.260018498848;29645.40709185761;30139.698603014567;42857.57142428536;43007.354889677306;43201;43436.565634344515;43443.782781085894;289804;295256;295917;295955;296225;22758.317250482512;23440.64839027382;23445.296641100795;24534.754652453248;24559.63160552629;39556.20887582174;42352.5764742361;43788;47111;48150.518494814605;36441.81779091088;36929.63070369262;37727;38117;38327.616723832405;36914.26171476609;37059.444108337855;37316.25367492581;37433.625663744104;37483.43774843434;36951.63048369482;37035.25929481341;37359.6264037367;39621.40567891426;40525.18949620932;32765;33390.470003247516;34158.82920585381;34199;34382.65617343895;25386.492270154864;26093.347666308495;26241.4751704961;26352;26432.81493543699 18:07:53 INFO - PROCESS | 7209 | |2;jquery.html;370.3481871905446;373.0654709951571;376.3658235872555;381.8300856119028;389.4878235120818;15692.372305107796;17219.569510762238;17220.225089870954;17649;17655.558611034732;15926.681466370666;16643.66712665746;16904.239309231085;17113.486595402126;17117.31530738769;4922.556969872702;5119.385673719159;5148.073346797575;5153.69077855328;5153.793848246066;3728.9813550932345;3912.2762288976487;5025.472325405816;5663.830085097454;5986.670733109677;5963.671998040106;5974.581779275452;6001.939980600204;6011.158437818709;6014.729337179816;5894.528437724972;5953.464188223059;6043.879122417571;6046.062860256668;6061.242344706911;3382.2728113455723;4951.207806750903;5275.709835959021;5278.5249327560705;5311.9468805311835;19560;20123.0944607493;20140.19439222444;20327.49181270465;20399.89800051005;1680.5210515003287;1897.5445892985722;1908.3511606054008;2156.1590979518;2222.844400891938;9638.662646807328;10002.549885255181;11273.943630281796;12954.935225323907;13545.932270338684;1730.2646375290576;1753.2811547265687;1794.6949018666728;1814.6189300246965;1861.7393564900924;67153.32846671568;67280.66359668219;68479.65760171167;68492;69925.65037174833;9932.354396964174;10086.43660232665;10150.796984060426;10175.542100605491;10302.124319432933;13851.376688049062;13935.30323483842;13959.511417100353;13981.091229070282;13983.30083495821;7309.780706578811;7335.559866408033;7380.483366164317;7484.5883476408235;7502.962485187539;6980;6986;7009.544379615309;7121.430285577141;7130.429565634737;4434.268345722984;4436.3567282743825;4468.486124095771;4470.619997300201;4515.9774201128785;1671.6990941630622;1774.7604073449918;1795.9191836367133;1816.4459839749004;1826.8629852761096;759.8176437654923;840.8906842110608;899.869518919753;919.1773362840221;979.8873129590046;15284.923575382052;16016.75974860378;16246.187690615421;16643.251053702614;17114.229859656345;848.893888263967;878.5826732302105;900.1988230474883;913.4245425381966;916.5417291354323;9316.906830931739;9376.812463750686;9412.529373531295;9530.428174309563;9737.367071140496;362366;364356;369422;388462;398756;72856.63571682108;73481;73523;74829.6258518704;74888;900.504722402689;914.3234006834895;933.4026223217146;937.8780758501488;938.2119020023077;14549.781753273921;14929.402823886923;15104.848951510345;15577.866957079614;16543;34751.304973899874;34983.825080874434;36224.637753622126;36258.637413625525;36714.4492832613;46917;47867;47986.28020579624;48601.513984859695;48702.75648621734;7154.606496642735;7320.524165929198;7393.8890916637365;7514.47398682087;7540.886886696813;5866.120081987736;7325.4872158948365;9586.904130958601;10295.021972912562;10629;8558.57207139628;8984.101589840964;9195.908040919505;9283;9302;9556.522173891139;9650.324477286522;9769;9797.31418800677;10003.34978226442;9312.161905428544;9733.9026609733;9828.655997040069;9957.20342372594;9968.451735154636;7410.073740782403;7458.440616953641;7575.507592006502;7590.316871481593;7621.3902887769955;7624.80937976555;9513.762155946157;9578.521073946415;9608;9811.754706132404 18:07:53 INFO - PROCESS | 7209 | |3;mootools.html;1206.945687444065;1390.0686540018185;1419.4180386041721;1507.2463768115942;1741.4253296412185;6745.898811517825;7520.924790752091;7679.7312094076715;7891.881621775671;7975.880361794563;7690.46166768325;7752.379809615231;7774.805629859256;7807.492512986666;7881.975343205375;1852.9629407411844;2191.7260342457193;2236.373815331706;2260.9208677696283;2261.3555136786017;2229.9219527316545;2287.3824067501764;2289.1873384948353;2289.244549298736;2293.7591552886956;2200.8569442986154;2206.2719302630176;2206.9558608827815;2269.8751568663715;2273.6930514380597;1816.527702797269;2126.2770657976207;2186.978130218701;2208.785168157507;2232.598132336179;2239.496113374494;2244.5623103494827;2244.5623103494827;2248.5952528544854;2249.966250506244;7802.258785415376;7810.726624568113;7812.843743125162;7873.173316801711;7890.0926393464915;4528.682992190548;4648.047150334173;4652.790624421909;4657.6273898088075;4660.277656963176;2909.2435966648613;2970.925726856846;3097.922551936197;3112.3464072544793;3130.2956834712236;945.7919257763281;950.2018304624149;957.2150836314225;959.9712008639752;977.130353984954;10642.574297028186;10647.14822814173;10659.893401065967;10671.893281067167;10747.301425407324;4183.184279065614;4327.17783621111;4354.455693038371;4366.192254432876;4370.497392799805;9123.543822808966;9150.633974641072;9198.080191980749;9199.310051746146;9218.354715170008;4641.767911604406;4660.557247061523;4661.533846615379;4662.487126416091;4676.111538807616;3617.222297206061;4104.138130992465;4199.9370009449885;4282.379055037002;4287.378330142112;3646.507721457622;3652.160003199279;3750.456183853326;3756.6243375662216;3789.3936970084656;1164.4527072276016;1181.9586314478952;1204.728935989413;1210.4371467267588;1222.6001228593389;829.8962629671291;831.4553967151526;847.7668641123616;858.2533196119416;858.5235194467131;5123.923141152885;5346.705931173745;5425.914817036609;5499.642523235977;5508.779648814003;392.5760179006666;396.61330203051796;405.989850253746;414.52122798168165;415.20695471649003;3479.8086105263947;3660.908477288036;3677.4116141417244;3690;3749.5687995880276;9271.814563708824;9580.904190958141;9634.181094606896;9646.565904534313;9686.467244301632;8642;9579.9042009579;9587.137157655845;9622.663206787729;9665.90334096664;7162.856742865113;7406.777796666216;7447.590382528904;7460.589667568227;7461.477696561297;5469.453054694658;5596.720163991703;5694.686792226467;5775;5801.709914504342;8723.302135828992;8875.689350872946;9083.046280140754;9088.363814533184;9116.08839116083;9081.091890810865;9136.817263654557;9143.268538517035;9160.816783664157;9194.724158275263;5787.797427090031;5926.703664816828;6784.185897692309;6952.165740111219;7020.157581090302;7490.70037198506;7515.924840751522;7543.47195696296;7562.735304264324;7566.583837888969;6707.128073350434;6729.226138993882;6858.759943401957;6937.7571784987285;6973.4072603829245;6767.627780472121;6801.931980680328;6859.759908403182;6876.17485901695;6913.792586222421;6085.391460853879;6117.0824376342125;6273.96863015682;6319.494440444845;6327.905081423874;4627.097715945493;4651.953480465152;4667.32323813052;4685.672002959896;4695.22528782747;4729.266963620645;4807.7836497357;4825.155597770447;4906.852794416173;4938.654294199371 18:07:53 INFO - PROCESS | 7209 | |4;prototype.html;301.95470679398085;304.44742791832823;310.57606367308625;312.4407310913465;312.5343238574525;4799.25611530213;4822.517748225176;4834;4836.7581620918945;4837.129316722993;4764.190087685093;4777.90444191116;4790.856274311776;4810.97594512027;4811.5910147637405;3582.570091589013;3652.1739130434785;3698.5561732592128;3744.644258795424;3803.3724435468116;3720.1354956683335;3746.4005759078555;3757.66181043706;3759;3778.8299526521373;3724.4599533067826;3728.179800443898;3728.422094575332;3739.476473293741;3742.850285988557;3553.0939610399314;3586.892393228194;3709.239605880815;3734.6638802507905;3763.981180094082;3322.9231730168644;3326.234965957819;3329.167708072982;3329.217633856042;3335.93328133436;4537.705049171826;4543.840965566223;4544.159330523864;4580.519045500241;4582.427196600425;4072.796360182009;4250.872473825791;4264.701470897038;4289.571042895716;4298.398224248608;4975.4029516457895;4981.551660350586;5012.649114561982;5019.723915184663;5022.321986531808;3677.8712745053795;3678.393065144248;3685.870994515179;3688.1517251032415;3720.1815600567834;5021.673591216596;5275.182346736262;5277.155655095205;5281.207818827168;5297.496737809902;3642.9635703643153;3648.3068217038676;3654.5979942206336;3657.2685462907525;3663.395539735967;3706.9073273168387;3710.7216958728204;3717.9442308365396;3724.6162456028164;3725.1245957200035;4232.365145228179;4233.491980962304;4253.76604286761;4260.062786187096;4268.615824575741;4848.806047758121;5107.948920510822;5109.233614957786;5113.184819389137;5115.232715092766;1322.3057894605408;1331.181323486049;1335.7128217433299;1349.6828245362333;1354.1265883505084;855.8930133733284;858.3347905373386;880.6521424037597;892.3797960417447;896.9103089690979;4311.461067366579;4424.6681498887065;4453.153900758845;4461.219286624828;4464.241079016575;5105;5114.769835357418;5125;5150.330457040635;5153.433122356538;4435.3125265583885;4456.910861782746;4475.7985890635;4477.552244775497;4485.618722408566;2593.4424098818845;2749.628800111959;2752.160591019718;2753.5869619557225;2770.459760346713;4861.51384861511;4877.804887804519;4879.756012199376;4880.487548807395;4886.267059941038;5073.771680274396;5220.791168353223;5269.736513174326;5288.223682566606;5343.358796944314;4901.7696947889335;4932;4948.896718894527;4979.954209616021;4998.500149984972;5186.429492755869;5226.843194704165;5227.947720522746;5239.6376941994595;5247.9766445542755;4859.927101093557;5095.643304968617;5117.02776472469;5119.846404607868;5128.487151284841;4894.877628059185;4910.729909854992;4922.532359425849;4933.865211001562;4945.7527123644395;3656.8902932912056;3658.2683463307976;3665.7250706197674;3675.4670572766263;3676.4485327200064;5084.644074914869;5184.740762961761;5184.885249671339;5211.81758638461;5233.973830130825;5005.424376196638;5020.246962955586;5036.471170527188;5066.518680725325;5075.3148324975655;5050.166722490895;5067.442581316126;5072.4420313766195;5081.33942587476;5081.754970032366;4797.544233297831;4983.950160498349;4985.825496107619;5002.124628189979;5008.824691135793;4855.878603034952;4941.950580494149;4979.477154898828;4996.3504744383;5093.567046801063;4850.7574621269505;4862.902741945212;4872.537108974641;4931.901361972812;4987.1521841287795 18:07:53 INFO - PROCESS | 7209 | |5;yui.html;594.3832774852401;667.2460120064327;763.6105586151065;766.1610536462575;780.6916268074108;11500.309981401111;11786.410679466035;11997.160198786067;12036.75926481471;12052.276863388202;13055.41250643721;13765.449382024706;13819.447222111103;14111.435542578285;14162.29188540574;628.9528285378615;780.6448066129925;795.9403044771636;799.532273619933;806.2139414071293;801.5591424716412;811.180707485441;811.8985126859143;811.8985126859143;812.2851890336495;843.8481073406784;844.9704260350888;845.3744229270327;848.1899785704637;848.3891598049395;659.6800551732406;823.6087858267335;840.6763396092518;840.697348954379;846.14539315292;620.0420350558398;620.93790620938;621.2824188062795;621.4313902778997;621.7171187109855;11394.886051139465;11397.259178153394;11416.885831141666;11433.199676022681;11492.367919764409;11158.1631377647;11596.246243994197;11710.297382157098;11844;11923.582674606434;10069.949650251701;10219.437930913797;11193.720156996058;11267.830982535268;11278.718032049183;3294.8846790362463;3316.8673253070087;3339.482380231068;3453.4647129694686;3454.1709989602564;44383.55616443827;45540;46249.7687511567;46590.53409465896;47495.287570686465;9987;10002.149817265468;10095.805812252513;10174.13519850821;10369.844452333222;16456.75314870254;17078.658426831644;17124.828751712572;17125.828741712674;17128.657426851394;6381.64900930444;6516.185476815398;6543.083968244449;6575.671216439159;6720.764773232913;3617.602063772983;3661.835217415223;3681.1165320323466;3700.962990370062;3715.6841668458483;2912.5339945608603;2973.7918345716025;2975.6280464941883;2989.327401334726;2992.745616622568;532.4888107416923;538.6471860931097;550.5457997152365;552.9778808847682;568.7696482924422;515.9793608255628;595.4373117404098;609.9573029887954;614.2567493333028;649.4674367019094;8344.165583441729;8354.624041918129;8451.873221901677;8482.321414286842;8696.130386961253;496.51093672732327;531.9574434045345;555.3030452739969;589.4047012517389;608.504069183614;4050.837966481308;4194.412782210493;4259.74441533509;4318.892027699269;4336.284513055373;57851;58136.127958080666;59092.704536477904;59539;59710.104348434805;53803.46196538063;54168;54348.18477722837;54696.45303546914;56304.43695562992;48870.51129488659;49110.508894911305;49398.25902611464;49441.75279123581;49620;7937.529710030033;7965.92034079675;8080.878786818085;8116.59417029158;8199.59002049907;32544.51183232301;32611.836940815145;32827.67172328246;33036.66963330336;33214.5017824728;42532;42656.57343426526;42727.145457091305;43194.784026079666;43411.34882976821;9184;9371.578278977326;9510.667126650535;9895.010498950047;10212.693619191139;11025.503852326505;11176;11215.887841121485;12158.93920530427;12292.139550231397;10576.735581610521;10638.787224255317;10791.298565593524;10801.837972430263;11017.779644407226;10585.841212381667;10646.73383165427;10936.671899843017;11321.943390283326;11401.144914131639;9808.460534670661;9812;9884.604615815575;9918.454485003394;9990.450525220891;8568.700095496628;8792.120787921156;8965.282777377664;9006.864897026418;9100.49841776126;9173.587188576663;9205.86191207119;9240.768980775534;9350.158485736316;9496.709233547488 18:07:53 INFO - PROCESS | 7209 | __end_tp_report 18:07:53 INFO - PROCESS | 7209 | __start_cc_report 18:07:53 INFO - PROCESS | 7209 | _x_x_mozilla_cycle_collect,1022 18:07:53 INFO - PROCESS | 7209 | __end_cc_report 18:07:53 INFO - PROCESS | 7209 | __startTimestamp1464829673714__endTimestamp 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | ------- Summary: start ------- 18:07:53 INFO - PROCESS | 7209 | Number of tests: 6 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#0] dojo.html Cycles:165 Average:4595.30 Median:4709.86 stddev:478.97 (10.2%) stddev-sans-first:454.91 18:07:53 INFO - PROCESS | 7209 | Values: 2628.3 2631.9 2660.6 2677.2 2694.4 4472.7 4567.7 4577.7 4647.9 4669.2 4553.8 4571.0 4583.9 4609.5 4619.9 4009.4 4167.5 4234.3 4258.5 4380.7 4264.1 4333.7 4335.7 4343.3 4388.8 4142.3 4180.0 4199.1 4201.8 4203.1 4233.4 4241.3 4246.4 4299.0 4309.4 4275.6 4287.7 4291.8 4330.2 4337.9 4361.1 4457.0 4488.6 4528.3 4538.5 4998.9 5029.7 5054.7 5055.8 5067.2 5062.9 5066.4 5086.5 5087.7 5094.9 4486.6 4619.7 4642.7 4645.5 4665.5 5118.7 5134.7 5139.0 5143.4 5152.7 4809.9 4894.4 4903.0 4941.0 4953.4 4894.2 4930.2 4946.6 4955.6 4957.4 4879.8 4900.2 4901.9 4923.3 4974.7 4777.1 4854.8 4894.6 4922.3 4965.1 4225.2 4242.3 4246.4 4266.0 4284.9 3694.4 3712.4 3716.0 3745.9 3762.3 4214.2 4249.3 4291.4 4303.7 4343.0 4936.9 4941.2 4991.6 4993.2 4996.5 4293.6 4300.7 4301.1 4310.1 4353.5 4346.0 4444.3 4474.4 4504.0 4510.1 4707.2 4708.6 4709.9 4730.0 4761.8 4984.6 4985.9 4986.2 4986.5 5043.4 4545.6 4676.7 4692.7 4698.1 4714.1 4911.6 4929.5 4930.4 4936.9 4985.1 4957.1 5007.2 5008.8 5011.8 5064.3 4649.0 4804.7 4808.3 4832.7 4927.0 4836.8 4855.9 4858.8 4862.0 4905.3 4796.1 4799.0 4805.7 4848.7 4854.2 4785.6 4860.0 4905.4 4906.9 4928.4 4774.0 4821.8 4829.1 4839.5 4894.8 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#1] ext.html Cycles:180 Average:46626.49 Median:37187.85 stddev:83225.85 (223.8%) stddev-sans-first:83387.40 18:07:53 INFO - PROCESS | 7209 | Values: 544.1 587.6 593.1 596.6 607.3 28120.2 28706.0 41036.4 42168.8 42283.0 38015.4 42228.6 42243.4 42292.4 42325.6 7561.9 7739.1 7743.3 7743.7 7755.1 7644.9 7741.9 7745.8 7760.7 7773.3 7692.1 7765.7 7766.1 7782.5 7788.1 7726.7 7779.0 7782.2 7785.4 7786.9 7653.6 7729.5 7745.8 7750.3 7751.4 39314.2 39784.6 39792.2 39804.8 39824.0 3206.4 3235.8 3277.2 3362.1 3555.9 29108.0 29582.6 29833.9 29949.9 29950.0 3564.1 3564.8 3565.6 3595.5 3604.2 249683.0 249761.0 250426.0 250846.0 251390.0 54194.5 54251.5 54591.0 54605.5 54651.0 33418.2 34803.7 34804.3 36149.5 36162.8 15126.0 15245.8 15264.1 15278.2 15279.3 10849.6 10864.0 10903.4 10909.8 10916.6 18550.9 18627.9 18676.5 18697.6 18698.3 6867.0 6888.3 7004.3 7008.3 7063.7 841.3 907.3 948.2 996.6 1040.1 27590.8 27892.6 28061.7 28209.6 28646.0 793.7 969.6 991.5 1060.3 1116.1 14609.1 14679.7 14727.0 14776.2 14987.3 386503.0 388430.0 388455.0 389266.0 389994.0 50409.7 50877.5 50922.2 50926.7 51091.2 1094.8 1177.4 1200.1 1237.6 1237.8 28183.9 29310.9 29599.3 29645.4 30139.7 42857.6 43007.4 43201.0 43436.6 43443.8 289804.0 295256.0 295917.0 295955.0 296225.0 22758.3 23440.6 23445.3 24534.8 24559.6 39556.2 42352.6 43788.0 47111.0 48150.5 36441.8 36929.6 37727.0 38117.0 38327.6 36914.3 37059.4 37316.3 37433.6 37483.4 36951.6 37035.3 37359.6 39621.4 40525.2 32765.0 33390.5 34158.8 34199.0 34382.7 25386.5 26093.3 26241.5 26352.0 26432.8 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#2] jquery.html Cycles:180 Average:23583.75 Median:5214.75 stddev:62223.98 (1193.2%) stddev-sans-first:62374.12 18:07:53 INFO - PROCESS | 7209 | Values: 370.3 373.1 376.4 381.8 389.5 15692.4 17219.6 17220.2 17649.0 17655.6 15926.7 16643.7 16904.2 17113.5 17117.3 4922.6 5119.4 5148.1 5153.7 5153.8 3729.0 3912.3 5025.5 5663.8 5986.7 5963.7 5974.6 6001.9 6011.2 6014.7 5894.5 5953.5 6043.9 6046.1 6061.2 3382.3 4951.2 5275.7 5278.5 5311.9 19560.0 20123.1 20140.2 20327.5 20399.9 1680.5 1897.5 1908.4 2156.2 2222.8 9638.7 10002.5 11273.9 12954.9 13545.9 1730.3 1753.3 1794.7 1814.6 1861.7 67153.3 67280.7 68479.7 68492.0 69925.7 9932.4 10086.4 10150.8 10175.5 10302.1 13851.4 13935.3 13959.5 13981.1 13983.3 7309.8 7335.6 7380.5 7484.6 7503.0 6980.0 6986.0 7009.5 7121.4 7130.4 4434.3 4436.4 4468.5 4470.6 4516.0 1671.7 1774.8 1795.9 1816.4 1826.9 759.8 840.9 899.9 919.2 979.9 15284.9 16016.8 16246.2 16643.3 17114.2 848.9 878.6 900.2 913.4 916.5 9316.9 9376.8 9412.5 9530.4 9737.4 362366.0 364356.0 369422.0 388462.0 398756.0 72856.6 73481.0 73523.0 74829.6 74888.0 900.5 914.3 933.4 937.9 938.2 14549.8 14929.4 15104.8 15577.9 16543.0 34751.3 34983.8 36224.6 36258.6 36714.4 46917.0 47867.0 47986.3 48601.5 48702.8 7154.6 7320.5 7393.9 7514.5 7540.9 5866.1 7325.5 9586.9 10295.0 10629.0 8558.6 8984.1 9195.9 9283.0 9302.0 9556.5 9650.3 9769.0 9797.3 10003.3 9312.2 9733.9 9828.7 9957.2 9968.5 7410.1 7458.4 7575.5 7590.3 7621.4 7624.8 9513.8 9578.5 9608.0 9811.8 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#3] mootools.html Cycles:180 Average:5167.57 Median:5235.31 stddev:2886.47 (55.1%) stddev-sans-first:2879.22 18:07:53 INFO - PROCESS | 7209 | Values: 1206.9 1390.1 1419.4 1507.2 1741.4 6745.9 7520.9 7679.7 7891.9 7975.9 7690.5 7752.4 7774.8 7807.5 7882.0 1853.0 2191.7 2236.4 2260.9 2261.4 2229.9 2287.4 2289.2 2289.2 2293.8 2200.9 2206.3 2207.0 2269.9 2273.7 1816.5 2126.3 2187.0 2208.8 2232.6 2239.5 2244.6 2244.6 2248.6 2250.0 7802.3 7810.7 7812.8 7873.2 7890.1 4528.7 4648.0 4652.8 4657.6 4660.3 2909.2 2970.9 3097.9 3112.3 3130.3 945.8 950.2 957.2 960.0 977.1 10642.6 10647.1 10659.9 10671.9 10747.3 4183.2 4327.2 4354.5 4366.2 4370.5 9123.5 9150.6 9198.1 9199.3 9218.4 4641.8 4660.6 4661.5 4662.5 4676.1 3617.2 4104.1 4199.9 4282.4 4287.4 3646.5 3652.2 3750.5 3756.6 3789.4 1164.5 1182.0 1204.7 1210.4 1222.6 829.9 831.5 847.8 858.3 858.5 5123.9 5346.7 5425.9 5499.6 5508.8 392.6 396.6 406.0 414.5 415.2 3479.8 3660.9 3677.4 3690.0 3749.6 9271.8 9580.9 9634.2 9646.6 9686.5 8642.0 9579.9 9587.1 9622.7 9665.9 7162.9 7406.8 7447.6 7460.6 7461.5 5469.5 5596.7 5694.7 5775.0 5801.7 8723.3 8875.7 9083.0 9088.4 9116.1 9081.1 9136.8 9143.3 9160.8 9194.7 5787.8 5926.7 6784.2 6952.2 7020.2 7490.7 7515.9 7543.5 7562.7 7566.6 6707.1 6729.2 6858.8 6937.8 6973.4 6767.6 6801.9 6859.8 6876.2 6913.8 6085.4 6117.1 6274.0 6319.5 6327.9 4627.1 4652.0 4667.3 4685.7 4695.2 4729.3 4807.8 4825.2 4906.9 4938.7 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#4] prototype.html Cycles:180 Average:4181.29 Median:4811.28 stddev:1207.92 (25.1%) stddev-sans-first:1175.70 18:07:53 INFO - PROCESS | 7209 | Values: 302.0 304.4 310.6 312.4 312.5 4799.3 4822.5 4834.0 4836.8 4837.1 4764.2 4777.9 4790.9 4811.0 4811.6 3582.6 3652.2 3698.6 3744.6 3803.4 3720.1 3746.4 3757.7 3759.0 3778.8 3724.5 3728.2 3728.4 3739.5 3742.9 3553.1 3586.9 3709.2 3734.7 3764.0 3322.9 3326.2 3329.2 3329.2 3335.9 4537.7 4543.8 4544.2 4580.5 4582.4 4072.8 4250.9 4264.7 4289.6 4298.4 4975.4 4981.6 5012.6 5019.7 5022.3 3677.9 3678.4 3685.9 3688.2 3720.2 5021.7 5275.2 5277.2 5281.2 5297.5 3643.0 3648.3 3654.6 3657.3 3663.4 3706.9 3710.7 3717.9 3724.6 3725.1 4232.4 4233.5 4253.8 4260.1 4268.6 4848.8 5107.9 5109.2 5113.2 5115.2 1322.3 1331.2 1335.7 1349.7 1354.1 855.9 858.3 880.7 892.4 896.9 4311.5 4424.7 4453.2 4461.2 4464.2 5105.0 5114.8 5125.0 5150.3 5153.4 4435.3 4456.9 4475.8 4477.6 4485.6 2593.4 2749.6 2752.2 2753.6 2770.5 4861.5 4877.8 4879.8 4880.5 4886.3 5073.8 5220.8 5269.7 5288.2 5343.4 4901.8 4932.0 4948.9 4980.0 4998.5 5186.4 5226.8 5227.9 5239.6 5248.0 4859.9 5095.6 5117.0 5119.8 5128.5 4894.9 4910.7 4922.5 4933.9 4945.8 3656.9 3658.3 3665.7 3675.5 3676.4 5084.6 5184.7 5184.9 5211.8 5234.0 5005.4 5020.2 5036.5 5066.5 5075.3 5050.2 5067.4 5072.4 5081.3 5081.8 4797.5 4984.0 4985.8 5002.1 5008.8 4855.9 4942.0 4979.5 4996.4 5093.6 4850.8 4862.9 4872.5 4931.9 4987.2 18:07:53 INFO - PROCESS | 7209 | 18:07:53 INFO - PROCESS | 7209 | [#5] yui.html Cycles:180 Average:13496.15 Median:25067.99 stddev:16235.51 (64.8%) stddev-sans-first:16252.14 18:07:53 INFO - PROCESS | 7209 | Values: 594.4 667.2 763.6 766.2 780.7 11500.3 11786.4 11997.2 12036.8 12052.3 13055.4 13765.4 13819.4 14111.4 14162.3 629.0 780.6 795.9 799.5 806.2 801.6 811.2 811.9 811.9 812.3 843.8 845.0 845.4 848.2 848.4 659.7 823.6 840.7 840.7 846.1 620.0 620.9 621.3 621.4 621.7 11394.9 11397.3 11416.9 11433.2 11492.4 11158.2 11596.2 11710.3 11844.0 11923.6 10069.9 10219.4 11193.7 11267.8 11278.7 3294.9 3316.9 3339.5 3453.5 3454.2 44383.6 45540.0 46249.8 46590.5 47495.3 9987.0 10002.1 10095.8 10174.1 10369.8 16456.8 17078.7 17124.8 17125.8 17128.7 6381.6 6516.2 6543.1 6575.7 6720.8 3617.6 3661.8 3681.1 3701.0 3715.7 2912.5 2973.8 2975.6 2989.3 2992.7 532.5 538.6 550.5 553.0 568.8 516.0 595.4 610.0 614.3 649.5 8344.2 8354.6 8451.9 8482.3 8696.1 496.5 532.0 555.3 589.4 608.5 4050.8 4194.4 4259.7 4318.9 4336.3 57851.0 58136.1 59092.7 59539.0 59710.1 53803.5 54168.0 54348.2 54696.5 56304.4 48870.5 49110.5 49398.3 49441.8 49620.0 7937.5 7965.9 8080.9 8116.6 8199.6 32544.5 32611.8 32827.7 33036.7 33214.5 42532.0 42656.6 42727.1 43194.8 43411.3 9184.0 9371.6 9510.7 9895.0 10212.7 11025.5 11176.0 11215.9 12158.9 12292.1 10576.7 10638.8 10791.3 10801.8 11017.8 10585.8 10646.7 10936.7 11321.9 11401.1 9808.5 9812.0 9884.6 9918.5 9990.5 8568.7 8792.1 8965.3 9006.9 9100.5 9173.6 9205.9 9240.8 9350.2 9496.7 18:07:53 INFO - PROCESS | 7209 | -------- Summary: end -------- 18:07:53 INFO - PROCESS | 7209 | 18:07:54 INFO - TEST-INFO | 7209: exit 0 18:07:54 INFO - TEST-OK | dromaeo_css | took 1118403ms 18:07:54 INFO - TEST-START | kraken 18:07:54 INFO - operating with platform_type : linux_ 18:07:54 INFO - Initialising browser for kraken test... 18:07:54 INFO - TEST-INFO | started process 7385 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0IBuSF/profile http://localhost:47143/getInfo.html) 18:07:58 INFO - PROCESS | 7385 | __metrics Screen width/height:1600/1200 18:07:58 INFO - PROCESS | 7385 | colorDepth:24 18:07:58 INFO - PROCESS | 7385 | Browser inner width/height: 1024/697 18:07:58 INFO - PROCESS | 7385 | __metrics 18:07:59 ERROR - PROCESS | 7385 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:08:00 INFO - TEST-INFO | 7385: exit 0 18:08:00 INFO - Browser initialized. 18:08:00 INFO - Running cycle 1/1 for kraken test... 18:08:00 INFO - TEST-INFO | started process 7464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0IBuSF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:08:12 INFO - PROCESS | 7464 | RSS: Main: 157573120 18:08:12 INFO - PROCESS | 7464 | 18:08:15 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47143/tests/kraken/driver.html?testName=audio-beat-detection) 18:08:16 INFO - PROCESS | 7464 | RSS: Main: 213700608 18:08:16 INFO - PROCESS | 7464 | 18:08:18 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47143/tests/kraken/driver.html?testName=audio-dft) 18:08:19 INFO - PROCESS | 7464 | RSS: Main: 202579968 18:08:19 INFO - PROCESS | 7464 | 18:08:22 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47143/tests/kraken/driver.html?testName=audio-fft) 18:08:23 INFO - PROCESS | 7464 | RSS: Main: 270848000 18:08:23 INFO - PROCESS | 7464 | 18:08:25 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47143/tests/kraken/driver.html?testName=audio-oscillator) 18:08:26 INFO - PROCESS | 7464 | RSS: Main: 180543488 18:08:26 INFO - PROCESS | 7464 | 18:08:28 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47143/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:08:28 INFO - PROCESS | 7464 | RSS: Main: 184610816 18:08:28 INFO - PROCESS | 7464 | 18:08:32 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47143/tests/kraken/driver.html?testName=imaging-darkroom) 18:08:32 INFO - PROCESS | 7464 | RSS: Main: 220200960 18:08:32 INFO - PROCESS | 7464 | 18:08:36 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47143/tests/kraken/driver.html?testName=imaging-desaturate) 18:08:36 INFO - PROCESS | 7464 | RSS: Main: 216027136 18:08:36 INFO - PROCESS | 7464 | 18:08:39 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47143/tests/kraken/driver.html?testName=json-parse-financial) 18:08:40 INFO - PROCESS | 7464 | RSS: Main: 218923008 18:08:40 INFO - PROCESS | 7464 | 18:08:42 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47143/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:08:42 INFO - PROCESS | 7464 | RSS: Main: 208453632 18:08:42 INFO - PROCESS | 7464 | 18:08:44 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:08:45 INFO - PROCESS | 7464 | RSS: Main: 210460672 18:08:45 INFO - PROCESS | 7464 | 18:08:47 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:08:48 INFO - PROCESS | 7464 | RSS: Main: 218296320 18:08:48 INFO - PROCESS | 7464 | 18:08:50 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:08:51 INFO - PROCESS | 7464 | RSS: Main: 221122560 18:08:51 INFO - PROCESS | 7464 | 18:08:54 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:08:55 INFO - PROCESS | 7464 | RSS: Main: 221478912 18:08:55 INFO - PROCESS | 7464 | 18:08:56 INFO - PROCESS | 7464 | Cycle 1(1): loaded http://localhost:47143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47143/tests/kraken/driver.html?testName=ai-astar) 18:08:57 INFO - PROCESS | 7464 | RSS: Main: 263593984 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | __start_tp_report 18:08:57 INFO - PROCESS | 7464 | _x_x_mozilla_page_load 18:08:57 INFO - PROCESS | 7464 | _x_x_mozilla_page_load_details 18:08:57 INFO - PROCESS | 7464 | |i|pagename|runs| 18:08:57 INFO - PROCESS | 7464 | |0;ai-astar;113;111;114;114;111;114;112;112;112;112 18:08:57 INFO - PROCESS | 7464 | |1;audio-beat-detection;158;135;132;184;133;131;159;132;188;129 18:08:57 INFO - PROCESS | 7464 | |2;audio-dft;161;162;162;161;163;163;163;163;162;164 18:08:57 INFO - PROCESS | 7464 | |3;audio-fft;75;75;75;108;76;76;76;76;103;76 18:08:57 INFO - PROCESS | 7464 | |4;audio-oscillator;87;85;111;85;86;85;121;84;85;123 18:08:57 INFO - PROCESS | 7464 | |5;imaging-gaussian-blur;117;116;117;117;116;117;116;117;119;117 18:08:57 INFO - PROCESS | 7464 | |6;imaging-darkroom;145;145;146;146;146;146;146;146;146;146 18:08:57 INFO - PROCESS | 7464 | |7;imaging-desaturate;94;95;94;94;95;95;95;94;95;95 18:08:57 INFO - PROCESS | 7464 | |8;json-parse-financial;65;65;65;65;64;66;67;66;65;65 18:08:57 INFO - PROCESS | 7464 | |9;json-stringify-tinderbox;65;65;65;65;65;70;65;64;65;65 18:08:57 INFO - PROCESS | 7464 | |10;stanford-crypto-aes;84;84;88;87;88;85;84;88;112;84 18:08:57 INFO - PROCESS | 7464 | |11;stanford-crypto-ccm;129;139;133;135;127;139;135;133;135;141 18:08:57 INFO - PROCESS | 7464 | |12;stanford-crypto-pbkdf2;167;167;175;167;171;173;173;168;169;172 18:08:57 INFO - PROCESS | 7464 | |13;stanford-crypto-sha256-iterative;66;70;67;68;65;65;65;66;66;65 18:08:57 INFO - PROCESS | 7464 | __end_tp_report 18:08:57 INFO - PROCESS | 7464 | __start_cc_report 18:08:57 INFO - PROCESS | 7464 | _x_x_mozilla_cycle_collect,1881 18:08:57 INFO - PROCESS | 7464 | __end_cc_report 18:08:57 INFO - PROCESS | 7464 | __startTimestamp1464829737048__endTimestamp 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | ------- Summary: start ------- 18:08:57 INFO - PROCESS | 7464 | Number of tests: 14 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.50 stddev:1.18 (1.0%) stddev-sans-first:1.24 18:08:57 INFO - PROCESS | 7464 | Values: 113.0 111.0 114.0 114.0 111.0 114.0 112.0 112.0 112.0 112.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:146.50 stddev:22.79 (15.6%) stddev-sans-first:23.89 18:08:57 INFO - PROCESS | 7464 | Values: 158.0 135.0 132.0 184.0 133.0 131.0 159.0 132.0 188.0 129.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:0.97 (0.6%) stddev-sans-first:0.88 18:08:57 INFO - PROCESS | 7464 | Values: 161.0 162.0 162.0 161.0 163.0 163.0 163.0 163.0 162.0 164.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:12.66 (16.7%) stddev-sans-first:13.20 18:08:57 INFO - PROCESS | 7464 | Values: 75.0 75.0 75.0 108.0 76.0 76.0 76.0 76.0 103.0 76.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:85.00 stddev:16.27 (19.1%) stddev-sans-first:16.98 18:08:57 INFO - PROCESS | 7464 | Values: 87.0 85.0 111.0 85.0 86.0 85.0 121.0 84.0 85.0 123.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 18:08:57 INFO - PROCESS | 7464 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 119.0 117.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 18:08:57 INFO - PROCESS | 7464 | Values: 145.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 18:08:57 INFO - PROCESS | 7464 | Values: 94.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:08:57 INFO - PROCESS | 7464 | Values: 65.0 65.0 65.0 65.0 64.0 66.0 67.0 66.0 65.0 65.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:1.65 (2.5%) stddev-sans-first:1.74 18:08:57 INFO - PROCESS | 7464 | Values: 65.0 65.0 65.0 65.0 65.0 70.0 65.0 64.0 65.0 65.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:86.00 stddev:8.49 (9.9%) stddev-sans-first:8.85 18:08:57 INFO - PROCESS | 7464 | Values: 84.0 84.0 88.0 87.0 88.0 85.0 84.0 88.0 112.0 84.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:135.00 stddev:4.40 (3.3%) stddev-sans-first:4.18 18:08:57 INFO - PROCESS | 7464 | Values: 129.0 139.0 133.0 135.0 127.0 139.0 135.0 133.0 135.0 141.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:171.50 stddev:2.97 (1.7%) stddev-sans-first:2.92 18:08:57 INFO - PROCESS | 7464 | Values: 167.0 167.0 175.0 167.0 171.0 173.0 173.0 168.0 169.0 172.0 18:08:57 INFO - PROCESS | 7464 | 18:08:57 INFO - PROCESS | 7464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.64 (2.5%) stddev-sans-first:1.73 18:08:57 INFO - PROCESS | 7464 | Values: 66.0 70.0 67.0 68.0 65.0 65.0 65.0 66.0 66.0 65.0 18:08:57 INFO - PROCESS | 7464 | -------- Summary: end -------- 18:08:57 INFO - PROCESS | 7464 | 18:08:58 INFO - TEST-INFO | 7464: exit 0 18:08:58 INFO - TEST-OK | kraken | took 63661ms 18:08:58 INFO - SUITE-END | took 1182s 18:08:58 INFO - Completed test suite (00:19:42) 18:08:58 INFO - javascript benchmark 18:08:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2628.3034995726125, 2631.88156532956, 2660.614210939413, 2677.223605154505, 2694.3533551947526, 4472.686911916167, 4567.6574256930735, 4577.725336479813, 4647.907041859165, 4669.206234940059, 4553.817847286105, 4571.040081582863, 4583.862484125473, 4609.516000819916, 4619.861404157872, 4009.3985902114623, 4167.541570427251, 4234.343676730096, 4258.467691538558, 4380.737155770664, 4264.147170565884, 4333.653307735373, 4335.739855608658, 4343.305071188611, 4388.846390376338, 4142.275101857163, 4179.958200418017, 4199.139176468817, 4201.76890271035, 4203.138356636882, 4233.449651545279, 4241.27898257297, 4246.44796176495, 4299.032717638537, 4309.396684464146, 4275.615194632467, 4287.742735435852, 4291.849785257532, 4330.220560299145, 4337.891552711208, 4361.062371590124, 4456.9748957739575, 4488.618467430272, 4528.298113792367, 4538.500764915857, 4998.900021999576, 5029.723365214912, 5054.7472626368535, 5055.848324550269, 5067.163917953533, 5062.898742025139, 5066.442691303953, 5086.54221120101, 5087.669301495391, 5094.949050509522, 4486.55134486552, 4619.69971951822, 4642.721436713808, 4645.512221216791, 4665.486796452387, 5118.692878427306, 5134.691918484903, 5139.049275883899, 5143.3570803649545, 5152.716600587004, 4809.86968062505, 4894.388201474816, 4902.950970490249, 4940.975295123501, 4953.430355509163, 4894.167991441393, 4930.211166213388, 4946.554810067111, 4955.628327875424, 4957.355543779286, 4879.780409881564, 4900.215965445512, 4901.852944411673, 4923.286123512071, 4974.651774375759, 4777.068471648065, 4854.80580776765, 4894.632902532324, 4922.286268491121, 4965.131102057126, 4225.176090662353, 4242.321228603409, 4246.40550322955, 4265.97867010663, 4284.892877678083, 3694.408894576855, 3712.406015037635, 3715.981420092882, 3745.9438108428394, 3762.3227818992846, 4214.199302132585, 4249.277622804131, 4291.442112525414, 4303.698741088157, 4342.978285108554, 4936.90126197474, 4941.184704523714, 4991.625628077909, 4993.226049962262, 4996.525330093708, 4293.635041021548, 4300.741955482681, 4301.118270754488, 4310.051788606502, 4353.521112677603, 4346.043870348518, 4444.31113177458, 4474.373587697725, 4503.954960450354, 4510.075434535912, 4707.152712511781, 4708.552687494751, 4709.858704238878, 4730.030343779448, 4761.76191190446, 4984.6261530384645, 4985.850424487271, 4986.15235409974, 4986.501349864984, 5043.394792624908, 4545.636349091998, 4676.69601475903, 4692.741899195577, 4698.060387922497, 4714.080754252887, 4911.582515486224, 4929.531694489017, 4930.383702037246, 4936.851894443173, 4985.127602669446, 4957.058158949788, 5007.223880298559, 5008.77460514285, 5011.799528018839, 5064.265681476091, 4649.023705021983, 4804.6876952997945, 4808.2547205183255, 4832.661713680009, 4927.039227350632, 4836.782344794563, 4855.907420830426, 4858.781354839111, 4861.975690121527, 4905.264210368473, 4796.112719146969, 4799.0, 4805.663603547718, 4848.7090774553635, 4854.247591623304, 4785.59322457581, 4860.0, 4905.435874874435, 4906.877328066684, 4928.383952005999, 4773.9761301193275, 4821.783019764049, 4829.13075646387, 4839.491853355347, 4894.755262236803], "value": 4565.513451995604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.1192503245885, 587.6092398554961, 593.0748033068417, 596.6360520082751, 607.298570151475, 28120.156395308124, 28706.0, 41036.38445423321, 42168.789156054256, 42283.0, 38015.42976855349, 42228.57771422285, 42243.36634950471, 42292.36561451581, 42325.576744232545, 7561.886571701429, 7739.071311442634, 7743.303102720754, 7743.690252389898, 7755.1081625613215, 7644.885326720104, 7741.916131741561, 7745.806354841117, 7760.650770715331, 7773.261540153676, 7692.115406728242, 7765.689372425096, 7766.145723970359, 7782.494137881048, 7788.06543214815, 7726.652300646484, 7779.0, 7782.221777822229, 7785.416093792988, 7786.922130778733, 7653.617319134077, 7729.497582657109, 7745.845083098363, 7750.302472777477, 7751.379889608817, 39314.21371572553, 39784.602153978376, 39792.20415591701, 39804.800975995226, 39824.0, 3206.422843888099, 3235.7896736712037, 3277.2462333663393, 3362.1090411041096, 3555.8933232003083, 29108.0, 29582.556261656093, 29833.850830745923, 29949.85025074861, 29950.0, 3564.108972756811, 3564.821758912044, 3565.554305711786, 3595.496630471736, 3604.2250916052913, 249683.0, 249761.0, 250426.0, 250846.0, 251390.0, 54194.45805541973, 54251.45748542543, 54591.0, 54605.45394546083, 54651.0, 33418.16454588606, 34803.651963480545, 34804.303913921576, 36149.45775813365, 36162.81918590443, 15126.0, 15245.771313430307, 15264.084154950739, 15278.236088195546, 15279.312430940634, 10849.566017359375, 10864.0, 10903.400312982703, 10909.781804364027, 10916.56333746657, 18550.907245463957, 18627.90686046561, 18676.533086672942, 18697.626047478974, 18698.252069917322, 6866.965665171642, 6888.276730943179, 7004.334588214111, 7008.334208250208, 7063.71745130189, 841.2807049972185, 907.3195103672246, 948.2414068745114, 996.6412091647002, 1040.1314902056868, 27590.753142463684, 27892.581611275848, 28061.719382806317, 28209.576856347172, 28646.0, 793.730131755206, 969.5976169889417, 991.5290237137447, 1060.3425875956957, 1116.1182665694173, 14609.123452592878, 14679.706405871822, 14727.0, 14776.187309698069, 14987.250637468083, 386503.0, 388430.0, 388455.0, 389266.0, 389994.0, 50409.74795126001, 50877.49122508727, 50922.23616645753, 50926.74536627293, 51091.233631495554, 1094.7755710079414, 1177.3995262416229, 1200.0759415250209, 1237.6101528018646, 1237.7524495101015, 28183.859080704464, 29310.853445732635, 29599.260018498848, 29645.40709185761, 30139.698603014567, 42857.57142428536, 43007.354889677306, 43201.0, 43436.565634344515, 43443.782781085894, 289804.0, 295256.0, 295917.0, 295955.0, 296225.0, 22758.317250482512, 23440.64839027382, 23445.296641100795, 24534.754652453248, 24559.63160552629, 39556.20887582174, 42352.5764742361, 43788.0, 47111.0, 48150.518494814605, 36441.81779091088, 36929.63070369262, 37727.0, 38117.0, 38327.616723832405, 36914.26171476609, 37059.444108337855, 37316.25367492581, 37433.625663744104, 37483.43774843434, 36951.63048369482, 37035.25929481341, 37359.6264037367, 39621.40567891426, 40525.18949620932, 32765.0, 33390.470003247516, 34158.82920585381, 34199.0, 34382.65617343895, 25386.492270154864, 26093.347666308495, 26241.4751704961, 26352.0, 26432.81493543699], "value": 17533.009628915803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.3481871905446, 373.0654709951571, 376.3658235872555, 381.8300856119028, 389.4878235120818, 15692.372305107796, 17219.569510762238, 17220.225089870954, 17649.0, 17655.558611034732, 15926.681466370666, 16643.66712665746, 16904.239309231085, 17113.486595402126, 17117.31530738769, 4922.556969872702, 5119.385673719159, 5148.073346797575, 5153.69077855328, 5153.793848246066, 3728.9813550932345, 3912.2762288976487, 5025.472325405816, 5663.830085097454, 5986.670733109677, 5963.671998040106, 5974.581779275452, 6001.939980600204, 6011.158437818709, 6014.729337179816, 5894.528437724972, 5953.464188223059, 6043.879122417571, 6046.062860256668, 6061.242344706911, 3382.2728113455723, 4951.207806750903, 5275.709835959021, 5278.5249327560705, 5311.9468805311835, 19560.0, 20123.0944607493, 20140.19439222444, 20327.49181270465, 20399.89800051005, 1680.5210515003287, 1897.5445892985722, 1908.3511606054008, 2156.1590979518, 2222.844400891938, 9638.662646807328, 10002.549885255181, 11273.943630281796, 12954.935225323907, 13545.932270338684, 1730.2646375290576, 1753.2811547265687, 1794.6949018666728, 1814.6189300246965, 1861.7393564900924, 67153.32846671568, 67280.66359668219, 68479.65760171167, 68492.0, 69925.65037174833, 9932.354396964174, 10086.43660232665, 10150.796984060426, 10175.542100605491, 10302.124319432933, 13851.376688049062, 13935.30323483842, 13959.511417100353, 13981.091229070282, 13983.30083495821, 7309.780706578811, 7335.559866408033, 7380.483366164317, 7484.5883476408235, 7502.962485187539, 6980.0, 6986.0, 7009.544379615309, 7121.430285577141, 7130.429565634737, 4434.268345722984, 4436.3567282743825, 4468.486124095771, 4470.619997300201, 4515.9774201128785, 1671.6990941630622, 1774.7604073449918, 1795.9191836367133, 1816.4459839749004, 1826.8629852761096, 759.8176437654923, 840.8906842110608, 899.869518919753, 919.1773362840221, 979.8873129590046, 15284.923575382052, 16016.75974860378, 16246.187690615421, 16643.251053702614, 17114.229859656345, 848.893888263967, 878.5826732302105, 900.1988230474883, 913.4245425381966, 916.5417291354323, 9316.906830931739, 9376.812463750686, 9412.529373531295, 9530.428174309563, 9737.367071140496, 362366.0, 364356.0, 369422.0, 388462.0, 398756.0, 72856.63571682108, 73481.0, 73523.0, 74829.6258518704, 74888.0, 900.504722402689, 914.3234006834895, 933.4026223217146, 937.8780758501488, 938.2119020023077, 14549.781753273921, 14929.402823886923, 15104.848951510345, 15577.866957079614, 16543.0, 34751.304973899874, 34983.825080874434, 36224.637753622126, 36258.637413625525, 36714.4492832613, 46917.0, 47867.0, 47986.28020579624, 48601.513984859695, 48702.75648621734, 7154.606496642735, 7320.524165929198, 7393.8890916637365, 7514.47398682087, 7540.886886696813, 5866.120081987736, 7325.4872158948365, 9586.904130958601, 10295.021972912562, 10629.0, 8558.57207139628, 8984.101589840964, 9195.908040919505, 9283.0, 9302.0, 9556.522173891139, 9650.324477286522, 9769.0, 9797.31418800677, 10003.34978226442, 9312.161905428544, 9733.9026609733, 9828.655997040069, 9957.20342372594, 9968.451735154636, 7410.073740782403, 7458.440616953641, 7575.507592006502, 7590.316871481593, 7621.3902887769955, 7624.80937976555, 9513.762155946157, 9578.521073946415, 9608.0, 9811.754706132404], "value": 8090.209858265723, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1206.945687444065, 1390.0686540018185, 1419.4180386041721, 1507.2463768115942, 1741.4253296412185, 6745.898811517825, 7520.924790752091, 7679.7312094076715, 7891.881621775671, 7975.880361794563, 7690.46166768325, 7752.379809615231, 7774.805629859256, 7807.492512986666, 7881.975343205375, 1852.9629407411844, 2191.7260342457193, 2236.373815331706, 2260.9208677696283, 2261.3555136786017, 2229.9219527316545, 2287.3824067501764, 2289.1873384948353, 2289.244549298736, 2293.7591552886956, 2200.8569442986154, 2206.2719302630176, 2206.9558608827815, 2269.8751568663715, 2273.6930514380597, 1816.527702797269, 2126.2770657976207, 2186.978130218701, 2208.785168157507, 2232.598132336179, 2239.496113374494, 2244.5623103494827, 2244.5623103494827, 2248.5952528544854, 2249.966250506244, 7802.258785415376, 7810.726624568113, 7812.843743125162, 7873.173316801711, 7890.0926393464915, 4528.682992190548, 4648.047150334173, 4652.790624421909, 4657.6273898088075, 4660.277656963176, 2909.2435966648613, 2970.925726856846, 3097.922551936197, 3112.3464072544793, 3130.2956834712236, 945.7919257763281, 950.2018304624149, 957.2150836314225, 959.9712008639752, 977.130353984954, 10642.574297028186, 10647.14822814173, 10659.893401065967, 10671.893281067167, 10747.301425407324, 4183.184279065614, 4327.17783621111, 4354.455693038371, 4366.192254432876, 4370.497392799805, 9123.543822808966, 9150.633974641072, 9198.080191980749, 9199.310051746146, 9218.354715170008, 4641.767911604406, 4660.557247061523, 4661.533846615379, 4662.487126416091, 4676.111538807616, 3617.222297206061, 4104.138130992465, 4199.9370009449885, 4282.379055037002, 4287.378330142112, 3646.507721457622, 3652.160003199279, 3750.456183853326, 3756.6243375662216, 3789.3936970084656, 1164.4527072276016, 1181.9586314478952, 1204.728935989413, 1210.4371467267588, 1222.6001228593389, 829.8962629671291, 831.4553967151526, 847.7668641123616, 858.2533196119416, 858.5235194467131, 5123.923141152885, 5346.705931173745, 5425.914817036609, 5499.642523235977, 5508.779648814003, 392.5760179006666, 396.61330203051796, 405.989850253746, 414.52122798168165, 415.20695471649003, 3479.8086105263947, 3660.908477288036, 3677.4116141417244, 3690.0, 3749.5687995880276, 9271.814563708824, 9580.904190958141, 9634.181094606896, 9646.565904534313, 9686.467244301632, 8642.0, 9579.9042009579, 9587.137157655845, 9622.663206787729, 9665.90334096664, 7162.856742865113, 7406.777796666216, 7447.590382528904, 7460.589667568227, 7461.477696561297, 5469.453054694658, 5596.720163991703, 5694.686792226467, 5775.0, 5801.709914504342, 8723.302135828992, 8875.689350872946, 9083.046280140754, 9088.363814533184, 9116.08839116083, 9081.091890810865, 9136.817263654557, 9143.268538517035, 9160.816783664157, 9194.724158275263, 5787.797427090031, 5926.703664816828, 6784.185897692309, 6952.165740111219, 7020.157581090302, 7490.70037198506, 7515.924840751522, 7543.47195696296, 7562.735304264324, 7566.583837888969, 6707.128073350434, 6729.226138993882, 6858.759943401957, 6937.7571784987285, 6973.4072603829245, 6767.627780472121, 6801.931980680328, 6859.759908403182, 6876.17485901695, 6913.792586222421, 6085.391460853879, 6117.0824376342125, 6273.96863015682, 6319.494440444845, 6327.905081423874, 4627.097715945493, 4651.953480465152, 4667.32323813052, 4685.672002959896, 4695.22528782747, 4729.266963620645, 4807.7836497357, 4825.155597770447, 4906.852794416173, 4938.654294199371], "value": 4099.499036575285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.95470679398085, 304.44742791832823, 310.57606367308625, 312.4407310913465, 312.5343238574525, 4799.25611530213, 4822.517748225176, 4834.0, 4836.7581620918945, 4837.129316722993, 4764.190087685093, 4777.90444191116, 4790.856274311776, 4810.97594512027, 4811.5910147637405, 3582.570091589013, 3652.1739130434785, 3698.5561732592128, 3744.644258795424, 3803.3724435468116, 3720.1354956683335, 3746.4005759078555, 3757.66181043706, 3759.0, 3778.8299526521373, 3724.4599533067826, 3728.179800443898, 3728.422094575332, 3739.476473293741, 3742.850285988557, 3553.0939610399314, 3586.892393228194, 3709.239605880815, 3734.6638802507905, 3763.981180094082, 3322.9231730168644, 3326.234965957819, 3329.167708072982, 3329.217633856042, 3335.93328133436, 4537.705049171826, 4543.840965566223, 4544.159330523864, 4580.519045500241, 4582.427196600425, 4072.796360182009, 4250.872473825791, 4264.701470897038, 4289.571042895716, 4298.398224248608, 4975.4029516457895, 4981.551660350586, 5012.649114561982, 5019.723915184663, 5022.321986531808, 3677.8712745053795, 3678.393065144248, 3685.870994515179, 3688.1517251032415, 3720.1815600567834, 5021.673591216596, 5275.182346736262, 5277.155655095205, 5281.207818827168, 5297.496737809902, 3642.9635703643153, 3648.3068217038676, 3654.5979942206336, 3657.2685462907525, 3663.395539735967, 3706.9073273168387, 3710.7216958728204, 3717.9442308365396, 3724.6162456028164, 3725.1245957200035, 4232.365145228179, 4233.491980962304, 4253.76604286761, 4260.062786187096, 4268.615824575741, 4848.806047758121, 5107.948920510822, 5109.233614957786, 5113.184819389137, 5115.232715092766, 1322.3057894605408, 1331.181323486049, 1335.7128217433299, 1349.6828245362333, 1354.1265883505084, 855.8930133733284, 858.3347905373386, 880.6521424037597, 892.3797960417447, 896.9103089690979, 4311.461067366579, 4424.6681498887065, 4453.153900758845, 4461.219286624828, 4464.241079016575, 5105.0, 5114.769835357418, 5125.0, 5150.330457040635, 5153.433122356538, 4435.3125265583885, 4456.910861782746, 4475.7985890635, 4477.552244775497, 4485.618722408566, 2593.4424098818845, 2749.628800111959, 2752.160591019718, 2753.5869619557225, 2770.459760346713, 4861.51384861511, 4877.804887804519, 4879.756012199376, 4880.487548807395, 4886.267059941038, 5073.771680274396, 5220.791168353223, 5269.736513174326, 5288.223682566606, 5343.358796944314, 4901.7696947889335, 4932.0, 4948.896718894527, 4979.954209616021, 4998.500149984972, 5186.429492755869, 5226.843194704165, 5227.947720522746, 5239.6376941994595, 5247.9766445542755, 4859.927101093557, 5095.643304968617, 5117.02776472469, 5119.846404607868, 5128.487151284841, 4894.877628059185, 4910.729909854992, 4922.532359425849, 4933.865211001562, 4945.7527123644395, 3656.8902932912056, 3658.2683463307976, 3665.7250706197674, 3675.4670572766263, 3676.4485327200064, 5084.644074914869, 5184.740762961761, 5184.885249671339, 5211.81758638461, 5233.973830130825, 5005.424376196638, 5020.246962955586, 5036.471170527188, 5066.518680725325, 5075.3148324975655, 5050.166722490895, 5067.442581316126, 5072.4420313766195, 5081.33942587476, 5081.754970032366, 4797.544233297831, 4983.950160498349, 4985.825496107619, 5002.124628189979, 5008.824691135793, 4855.878603034952, 4941.950580494149, 4979.477154898828, 4996.3504744383, 5093.567046801063, 4850.7574621269505, 4862.902741945212, 4872.537108974641, 4931.901361972812, 4987.1521841287795], "value": 3803.564542222663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.3832774852401, 667.2460120064327, 763.6105586151065, 766.1610536462575, 780.6916268074108, 11500.309981401111, 11786.410679466035, 11997.160198786067, 12036.75926481471, 12052.276863388202, 13055.41250643721, 13765.449382024706, 13819.447222111103, 14111.435542578285, 14162.29188540574, 628.9528285378615, 780.6448066129925, 795.9403044771636, 799.532273619933, 806.2139414071293, 801.5591424716412, 811.180707485441, 811.8985126859143, 811.8985126859143, 812.2851890336495, 843.8481073406784, 844.9704260350888, 845.3744229270327, 848.1899785704637, 848.3891598049395, 659.6800551732406, 823.6087858267335, 840.6763396092518, 840.697348954379, 846.14539315292, 620.0420350558398, 620.93790620938, 621.2824188062795, 621.4313902778997, 621.7171187109855, 11394.886051139465, 11397.259178153394, 11416.885831141666, 11433.199676022681, 11492.367919764409, 11158.1631377647, 11596.246243994197, 11710.297382157098, 11844.0, 11923.582674606434, 10069.949650251701, 10219.437930913797, 11193.720156996058, 11267.830982535268, 11278.718032049183, 3294.8846790362463, 3316.8673253070087, 3339.482380231068, 3453.4647129694686, 3454.1709989602564, 44383.55616443827, 45540.0, 46249.7687511567, 46590.53409465896, 47495.287570686465, 9987.0, 10002.149817265468, 10095.805812252513, 10174.13519850821, 10369.844452333222, 16456.75314870254, 17078.658426831644, 17124.828751712572, 17125.828741712674, 17128.657426851394, 6381.64900930444, 6516.185476815398, 6543.083968244449, 6575.671216439159, 6720.764773232913, 3617.602063772983, 3661.835217415223, 3681.1165320323466, 3700.962990370062, 3715.6841668458483, 2912.5339945608603, 2973.7918345716025, 2975.6280464941883, 2989.327401334726, 2992.745616622568, 532.4888107416923, 538.6471860931097, 550.5457997152365, 552.9778808847682, 568.7696482924422, 515.9793608255628, 595.4373117404098, 609.9573029887954, 614.2567493333028, 649.4674367019094, 8344.165583441729, 8354.624041918129, 8451.873221901677, 8482.321414286842, 8696.130386961253, 496.51093672732327, 531.9574434045345, 555.3030452739969, 589.4047012517389, 608.504069183614, 4050.837966481308, 4194.412782210493, 4259.74441533509, 4318.892027699269, 4336.284513055373, 57851.0, 58136.127958080666, 59092.704536477904, 59539.0, 59710.104348434805, 53803.46196538063, 54168.0, 54348.18477722837, 54696.45303546914, 56304.43695562992, 48870.51129488659, 49110.508894911305, 49398.25902611464, 49441.75279123581, 49620.0, 7937.529710030033, 7965.92034079675, 8080.878786818085, 8116.59417029158, 8199.59002049907, 32544.51183232301, 32611.836940815145, 32827.67172328246, 33036.66963330336, 33214.5017824728, 42532.0, 42656.57343426526, 42727.145457091305, 43194.784026079666, 43411.34882976821, 9184.0, 9371.578278977326, 9510.667126650535, 9895.010498950047, 10212.693619191139, 11025.503852326505, 11176.0, 11215.887841121485, 12158.93920530427, 12292.139550231397, 10576.735581610521, 10638.787224255317, 10791.298565593524, 10801.837972430263, 11017.779644407226, 10585.841212381667, 10646.73383165427, 10936.671899843017, 11321.943390283326, 11401.144914131639, 9808.460534670661, 9812.0, 9884.604615815575, 9918.454485003394, 9990.450525220891, 8568.700095496628, 8792.120787921156, 8965.282777377664, 9006.864897026418, 9100.49841776126, 9173.587188576663, 9205.86191207119, 9240.768980775534, 9350.158485736316, 9496.709233547488], "value": 6005.648251297343, "unit": "score"}], "name": "dromaeo_css", "value": 6268.153250374971, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 114.0, 114.0, 111.0, 114.0, 112.0, 112.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 135.0, 132.0, 184.0, 133.0, 131.0, 159.0, 132.0, 188.0, 129.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 163.0, 163.0, 163.0, 163.0, 162.0, 164.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 108.0, 76.0, 76.0, 76.0, 76.0, 103.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 111.0, 85.0, 86.0, 85.0, 121.0, 84.0, 85.0, 123.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 119.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 64.0, 66.0, 67.0, 66.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 70.0, 65.0, 64.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 88.0, 87.0, 88.0, 85.0, 84.0, 88.0, 112.0, 84.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 139.0, 133.0, 135.0, 127.0, 139.0, 135.0, 133.0, 135.0, 141.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 175.0, 167.0, 171.0, 173.0, 173.0, 168.0, 169.0, 172.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 68.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1547.3, "alertThreshold": 2.0}]} 18:08:58 INFO - Return code: 0 18:08:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:08:58 ERROR - # TBPL SUCCESS # 18:08:58 INFO - Running post-action listener: _resource_record_post_action 18:08:58 INFO - ##### 18:08:58 INFO - ##### Finished run-tests step (success) 18:08:58 INFO - ##### 18:08:58 INFO - Running post-run listener: _resource_record_post_run 18:08:58 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 522334208; Read time: 0; Write time: 390228 18:08:58 INFO - TinderboxPrint: CPU usage
13.1% 18:08:58 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:08:58 INFO - TinderboxPrint: I/O write bytes / time
522,334,208 / 390,228 18:08:58 INFO - TinderboxPrint: CPU idle
8,316.6 (86.9%) 18:08:58 INFO - TinderboxPrint: CPU user
1,210.9 (12.7%) 18:08:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:08:58 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 237223936; Read time: 0; Write time: 227312 18:08:58 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 285110272; Read time: 0; Write time: 162916 18:08:58 INFO - Running post-run listener: _upload_blobber_files 18:08:58 INFO - Blob upload gear active. 18:08:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:08:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:08:58 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:08:58 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:08:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:08:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:08:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:09:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:09:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:09:00 INFO - (blobuploader) - INFO - Done attempting. 18:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:09:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:09:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:09:00 INFO - (blobuploader) - INFO - Done attempting. 18:09:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:09:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:09:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:09:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:09:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:09:05 INFO - (blobuploader) - INFO - Done attempting. 18:09:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:09:05 INFO - Return code: 0 18:09:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:09:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:09:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9be0c160538e98f0934821a660ce81186b67f32fef6cf105cf9553f917009f044602c4f939cc83d5f69ef6d7e4bb81a2550142ab335d30caae13a68099da94c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d23ee228ec973ecd3401e05bde384010bfbd3ba995a7925b955141cdaa5f3afb9ca4e2a08665e4accb1455228ffa244153fb61b10f814e330c3dd7b5b25c480", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/076792736420970245f34b0bb1daac7ca8f8f6d6c267ad2369aada17fa0e470345c14faf46f7b4f3212b85c3fa2b76d8a8c82700f34cdd8fe59ba495c29e3e1b"} 18:09:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:09:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:09:05 INFO - Contents: 18:09:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9be0c160538e98f0934821a660ce81186b67f32fef6cf105cf9553f917009f044602c4f939cc83d5f69ef6d7e4bb81a2550142ab335d30caae13a68099da94c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d23ee228ec973ecd3401e05bde384010bfbd3ba995a7925b955141cdaa5f3afb9ca4e2a08665e4accb1455228ffa244153fb61b10f814e330c3dd7b5b25c480", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/076792736420970245f34b0bb1daac7ca8f8f6d6c267ad2369aada17fa0e470345c14faf46f7b4f3212b85c3fa2b76d8a8c82700f34cdd8fe59ba495c29e3e1b"} 18:09:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:09:05 INFO - Copying logs to upload dir... 18:09:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:09:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.274956 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-01 18:09:05.355573) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:09:05.359905) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9be0c160538e98f0934821a660ce81186b67f32fef6cf105cf9553f917009f044602c4f939cc83d5f69ef6d7e4bb81a2550142ab335d30caae13a68099da94c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d23ee228ec973ecd3401e05bde384010bfbd3ba995a7925b955141cdaa5f3afb9ca4e2a08665e4accb1455228ffa244153fb61b10f814e330c3dd7b5b25c480", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/076792736420970245f34b0bb1daac7ca8f8f6d6c267ad2369aada17fa0e470345c14faf46f7b4f3212b85c3fa2b76d8a8c82700f34cdd8fe59ba495c29e3e1b"} build_url:https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/JG0JAU_OSu6q5ffNNKKtjA/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/Firefox-Non-PGO/sha512/9be0c160538e98f0934821a660ce81186b67f32fef6cf105cf9553f917009f044602c4f939cc83d5f69ef6d7e4bb81a2550142ab335d30caae13a68099da94c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2d23ee228ec973ecd3401e05bde384010bfbd3ba995a7925b955141cdaa5f3afb9ca4e2a08665e4accb1455228ffa244153fb61b10f814e330c3dd7b5b25c480", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/076792736420970245f34b0bb1daac7ca8f8f6d6c267ad2369aada17fa0e470345c14faf46f7b4f3212b85c3fa2b76d8a8c82700f34cdd8fe59ba495c29e3e1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 18:09:05.387592) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:09:05.387906) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464828103.54729-2010162719 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 18:09:05.467288) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:09:05.470645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 18:09:05.470937) ========= ========= Total master_lag: 0.19 =========