builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-014 starttime: 1461191531.88 results: success (0) buildid: 20160420141806 builduid: b804f05c0eda45409d39e1075aa20396 revision: 4feb4dd910a5a2d3061dbdd376a80975206819c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:11.878959) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:11.879714) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:11.879985) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:11.916001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:11.916447) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-20 15:32:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-04-20 15:32:12 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082088 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:12.024021) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:12.024334) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017105 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:12.078178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 15:32:12.078504) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4feb4dd910a5a2d3061dbdd376a80975206819c6 --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 4feb4dd910a5a2d3061dbdd376a80975206819c6 --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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-20 15:32:12,487 truncating revision to first 12 chars 2016-04-20 15:32:12,487 Setting DEBUG logging. 2016-04-20 15:32:12,487 attempt 1/10 2016-04-20 15:32:12,487 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4feb4dd910a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 15:32:13,738 unpacking tar archive at: mozilla-central-4feb4dd910a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.526126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 15:32:13.621938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:13.622301) ========= script_repo_revision: 4feb4dd910a5a2d3061dbdd376a80975206819c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:13.622687) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:13.622962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 15:32:13.633116) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-04-20 15:32:13.633721) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:32:14 INFO - MultiFileLogger online at 20160420 15:32:14 in /builds/slave/test 15:32:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 15:32:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:32:14 INFO - {'append_to_log': False, 15:32:14 INFO - 'base_work_dir': '/builds/slave/test', 15:32:14 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:32:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:32:14 INFO - 'branch': 'Firefox-Non-PGO', 15:32:14 INFO - 'buildbot_json_path': 'buildprops.json', 15:32:14 INFO - 'config_files': ('talos/linux_config.py',), 15:32:14 INFO - 'default_actions': ('clobber', 15:32:14 INFO - 'read-buildbot-config', 15:32:14 INFO - 'download-and-extract', 15:32:14 INFO - 'populate-webroot', 15:32:14 INFO - 'create-virtualenv', 15:32:14 INFO - 'install', 15:32:14 INFO - 'run-tests'), 15:32:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:32:14 INFO - 'download_minidump_stackwalk': True, 15:32:14 INFO - 'download_symbols': 'ondemand', 15:32:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:32:14 INFO - 'tooltool.py': '/tools/tooltool.py', 15:32:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:32:14 INFO - '/tools/misc-python/virtualenv.py')}, 15:32:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:32:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:32:14 INFO - 'installer_path': 'installer.exe', 15:32:14 INFO - 'log_level': 'info', 15:32:14 INFO - 'log_name': 'talos', 15:32:14 INFO - 'log_to_console': True, 15:32:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:32:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:32:14 INFO - 'opt_config_files': (), 15:32:14 INFO - 'pip_index': False, 15:32:14 INFO - 'sps_profile': False, 15:32:14 INFO - 'sps_profile_interval': 0, 15:32:14 INFO - 'suite': 'dromaeojs-e10s', 15:32:14 INFO - 'system_bits': '32', 15:32:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:32:14 INFO - 'title': 'talos-linux64-ix-014', 15:32:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:32:14 INFO - 'use_talos_json': True, 15:32:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:32:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:32:14 INFO - 'work_dir': 'build'} 15:32:14 INFO - ##### 15:32:14 INFO - ##### Running clobber step. 15:32:14 INFO - ##### 15:32:14 INFO - Running pre-action listener: _resource_record_pre_action 15:32:14 INFO - Running main action method: clobber 15:32:14 INFO - rmtree: /builds/slave/test/build 15:32:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:32:14 INFO - Running post-action listener: _resource_record_post_action 15:32:14 INFO - ##### 15:32:14 INFO - ##### Running read-buildbot-config step. 15:32:14 INFO - ##### 15:32:14 INFO - Running pre-action listener: _resource_record_pre_action 15:32:14 INFO - Running main action method: read_buildbot_config 15:32:14 INFO - Using buildbot properties: 15:32:14 INFO - { 15:32:14 INFO - "project": "", 15:32:14 INFO - "product": "firefox", 15:32:14 INFO - "script_repo_revision": "production", 15:32:14 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:32:14 INFO - "repository": "", 15:32:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:32:14 INFO - "buildid": "20160420141806", 15:32:14 INFO - "pgo_build": "False", 15:32:14 INFO - "basedir": "/builds/slave/test", 15:32:14 INFO - "buildnumber": 296, 15:32:14 INFO - "slavename": "talos-linux64-ix-014", 15:32:14 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:32:14 INFO - "platform": "ubuntu64_hw", 15:32:14 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:32:14 INFO - "branch": "mozilla-central", 15:32:14 INFO - "repo_path": "mozilla-central", 15:32:14 INFO - "revision": "4feb4dd910a5a2d3061dbdd376a80975206819c6", 15:32:14 INFO - "stage_platform": "linux64", 15:32:14 INFO - "builduid": "b804f05c0eda45409d39e1075aa20396", 15:32:14 INFO - "slavebuilddir": "test" 15:32:14 INFO - } 15:32:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 15:32:14 INFO - Running post-action listener: _resource_record_post_action 15:32:14 INFO - ##### 15:32:14 INFO - ##### Running download-and-extract step. 15:32:14 INFO - ##### 15:32:14 INFO - Running pre-action listener: _resource_record_pre_action 15:32:14 INFO - Running main action method: download_and_extract 15:32:14 INFO - mkdir: /builds/slave/test/build/tests 15:32: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')]} 15:32:14 INFO - https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:32:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 15:32:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 15:32:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 15:32:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:32:18 INFO - Downloaded 1361 bytes. 15:32:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 15:32:18 INFO - Using the following test package requirements: 15:32:18 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 15:32:18 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:32:18 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:32:18 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'jsshell-linux-x86_64.zip'], 15:32:18 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:32:18 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 15:32:18 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:32:18 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:32:18 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:32:18 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 15:32:18 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:32:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:32: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')]} 15:32:18 INFO - https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:23 INFO - Downloaded 22274940 bytes. 15:32:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:25 INFO - Return code: 0 15:32:25 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:32:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:25 INFO - https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 15:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:32:27 INFO - Downloaded 22274940 bytes. 15:32:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:32:29 INFO - Return code: 0 15:32: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')]} 15:32:29 INFO - https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:32:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 15:32:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:32:30 INFO - Downloaded 10845952 bytes. 15:32:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:32:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:32:30 INFO - Return code: 0 15:32: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')]} 15:32:30 INFO - https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:32:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 15:32:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 15:32:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 15:32:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:32:32 INFO - Downloaded 54610685 bytes. 15:32:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 15:32:32 INFO - mkdir: /builds/slave/test/properties 15:32:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:32:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:32:32 INFO - Contents: 15:32:32 INFO - build_url:https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 15:32:32 INFO - Running post-action listener: _resource_record_post_action 15:32:32 INFO - Running post-action listener: set_extra_try_arguments 15:32:32 INFO - ##### 15:32:32 INFO - ##### Running populate-webroot step. 15:32:32 INFO - ##### 15:32:32 INFO - Running pre-action listener: _resource_record_pre_action 15:32:32 INFO - Running main action method: populate_webroot 15:32:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:32:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:32:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:32:32 INFO - '--tppagecycles', 15:32:32 INFO - '7'], 15:32:32 INFO - 'tests': ['tsvgm']}}, 15:32:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:32:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:32:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:32:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:32:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:32:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['damp', 'tps']}, 15:32:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'tests': ['damp', 'tps']}, 15:32:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['dromaeo_dom']}, 15:32:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:32:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'other-e10s': {'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['a11yr', 15:32:32 INFO - 'ts_paint', 15:32:32 INFO - 'tpaint', 15:32:32 INFO - 'sessionrestore', 15:32:32 INFO - 'sessionrestore_no_auto_restore', 15:32:32 INFO - 'tabpaint']}, 15:32:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['tsvgx', 15:32:32 INFO - 'tsvgr_opacity', 15:32:32 INFO - 'tart', 15:32:32 INFO - 'tscrollx', 15:32:32 INFO - 'cart']}, 15:32:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:32:32 INFO - 'tsvgr_opacity', 15:32:32 INFO - 'tart', 15:32:32 INFO - 'tscrollx', 15:32:32 INFO - 'cart']}, 15:32:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'talos_options': ['--disable-e10s'], 15:32:32 INFO - 'tests': ['tp5o']}, 15:32:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'tests': ['tp5o']}, 15:32:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'talos_options': ['--disable-e10s', 15:32:32 INFO - '--xperf_path', 15:32:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:32 INFO - 'tests': ['tp5n']}, 15:32:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:32:32 INFO - 'talos_options': ['--xperf_path', 15:32:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:32:32 INFO - 'tests': ['tp5n']}}, 15:32:32 INFO - 'talos.zip': {'path': '', 15:32:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:32:32 INFO - Running post-action listener: _resource_record_post_action 15:32:32 INFO - ##### 15:32:32 INFO - ##### Running create-virtualenv step. 15:32:32 INFO - ##### 15:32:32 INFO - Running pre-action listener: _resource_record_pre_action 15:32:32 INFO - Running main action method: create_virtualenv 15:32:32 INFO - Creating virtualenv /builds/slave/test/build/venv 15:32:32 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 15:32:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:32:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:32:33 INFO - Using real prefix '/usr' 15:32:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:32:33 INFO - Installing distribute.............................................................................................................................................................................................done. 15:32:35 INFO - Installing pip.................done. 15:32:35 INFO - Return code: 0 15:32:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 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 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:35 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 15:32:35 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 15:32:35 INFO - Using env: {'DISPLAY': ':0', 15:32:35 INFO - 'HOME': '/home/cltbld', 15:32:35 INFO - 'LANG': 'en_US.UTF-8', 15:32:35 INFO - 'LANGUAGE': 'en_US:en', 15:32:35 INFO - 'LOGNAME': 'cltbld', 15:32:35 INFO - 'MAIL': '/var/mail/cltbld', 15:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:35 INFO - 'MOZ_NO_REMOTE': '1', 15:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:35 INFO - 'NO_EM_RESTART': '1', 15:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:35 INFO - 'PWD': '/builds/slave/test', 15:32:35 INFO - 'SHELL': '/bin/bash', 15:32:35 INFO - 'SHLVL': '1', 15:32:35 INFO - 'TERM': 'linux', 15:32:35 INFO - 'TMOUT': '86400', 15:32:35 INFO - 'USER': 'cltbld', 15:32:35 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:35 INFO - '_': '/tools/buildbot/bin/python'} 15:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:35 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 15:32:35 INFO - Cleaning up... 15:32:35 INFO - Return code: 0 15:32:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:35 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:35 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>=0.7.1'] in /builds/slave/test/build 15:32:35 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>=0.7.1 15:32:35 INFO - Using env: {'DISPLAY': ':0', 15:32:35 INFO - 'HOME': '/home/cltbld', 15:32:35 INFO - 'LANG': 'en_US.UTF-8', 15:32:35 INFO - 'LANGUAGE': 'en_US:en', 15:32:35 INFO - 'LOGNAME': 'cltbld', 15:32:35 INFO - 'MAIL': '/var/mail/cltbld', 15:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:35 INFO - 'MOZ_NO_REMOTE': '1', 15:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:35 INFO - 'NO_EM_RESTART': '1', 15:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:35 INFO - 'PWD': '/builds/slave/test', 15:32:35 INFO - 'SHELL': '/bin/bash', 15:32:35 INFO - 'SHLVL': '1', 15:32:35 INFO - 'TERM': 'linux', 15:32:35 INFO - 'TMOUT': '86400', 15:32:35 INFO - 'USER': 'cltbld', 15:32:35 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:35 INFO - '_': '/tools/buildbot/bin/python'} 15:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:35 INFO - Downloading/unpacking psutil>=0.7.1 15:32:35 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 15:32:35 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 15:32:35 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 15:32:35 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 15:32:35 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 15:32:35 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 15:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:32:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:37 INFO - Installing collected packages: psutil 15:32:37 INFO - Running setup.py install for psutil 15:32:38 INFO - building 'psutil._psutil_linux' extension 15:32:38 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 15:32:38 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 15:32:38 INFO - building 'psutil._psutil_posix' extension 15:32:38 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 15:32:38 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 15:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:32:38 INFO - Successfully installed psutil 15:32:38 INFO - Cleaning up... 15:32:38 INFO - Return code: 0 15:32:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:32:38 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')]} 15:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:38 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')]} 15:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:38 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:38 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.0.0'] in /builds/slave/test/build 15:32:38 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.0.0 15:32:38 INFO - Using env: {'DISPLAY': ':0', 15:32:38 INFO - 'HOME': '/home/cltbld', 15:32:38 INFO - 'LANG': 'en_US.UTF-8', 15:32:38 INFO - 'LANGUAGE': 'en_US:en', 15:32:38 INFO - 'LOGNAME': 'cltbld', 15:32:38 INFO - 'MAIL': '/var/mail/cltbld', 15:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:38 INFO - 'MOZ_NO_REMOTE': '1', 15:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:38 INFO - 'NO_EM_RESTART': '1', 15:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:38 INFO - 'PWD': '/builds/slave/test', 15:32:38 INFO - 'SHELL': '/bin/bash', 15:32:38 INFO - 'SHLVL': '1', 15:32:38 INFO - 'TERM': 'linux', 15:32:38 INFO - 'TMOUT': '86400', 15:32:38 INFO - 'USER': 'cltbld', 15:32:38 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:38 INFO - '_': '/tools/buildbot/bin/python'} 15:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:38 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 15:32:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:32:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:32:40 INFO - Installing collected packages: mozsystemmonitor 15:32:40 INFO - Running setup.py install for mozsystemmonitor 15:32:40 INFO - Successfully installed mozsystemmonitor 15:32:40 INFO - Cleaning up... 15:32:40 INFO - Return code: 0 15:32:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:32: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')]} 15:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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')]} 15:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32: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', '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 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:32: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 blobuploader==1.2.4 15:32:40 INFO - Using env: {'DISPLAY': ':0', 15:32:40 INFO - 'HOME': '/home/cltbld', 15:32:40 INFO - 'LANG': 'en_US.UTF-8', 15:32:40 INFO - 'LANGUAGE': 'en_US:en', 15:32:40 INFO - 'LOGNAME': 'cltbld', 15:32:40 INFO - 'MAIL': '/var/mail/cltbld', 15:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:40 INFO - 'MOZ_NO_REMOTE': '1', 15:32:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:40 INFO - 'NO_EM_RESTART': '1', 15:32:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:40 INFO - 'PWD': '/builds/slave/test', 15:32:40 INFO - 'SHELL': '/bin/bash', 15:32:40 INFO - 'SHLVL': '1', 15:32:40 INFO - 'TERM': 'linux', 15:32:40 INFO - 'TMOUT': '86400', 15:32:40 INFO - 'USER': 'cltbld', 15:32:40 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:40 INFO - '_': '/tools/buildbot/bin/python'} 15:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:32:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:32:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:32:43 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 15:32:43 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 15:32:43 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 15:32:43 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 15:32:43 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 15:32:43 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 15:32:43 INFO - Downloading docopt-0.6.1.tar.gz 15:32:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:32:43 INFO - Installing collected packages: blobuploader, requests, docopt 15:32:43 INFO - Running setup.py install for blobuploader 15:32:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:32:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:32:43 INFO - Running setup.py install for requests 15:32:44 INFO - Running setup.py install for docopt 15:32:44 INFO - Successfully installed blobuploader requests docopt 15:32:44 INFO - Cleaning up... 15:32:44 INFO - Return code: 0 15:32:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:44 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 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:44 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 15:32:44 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 15:32:44 INFO - Using env: {'DISPLAY': ':0', 15:32:44 INFO - 'HOME': '/home/cltbld', 15:32:44 INFO - 'LANG': 'en_US.UTF-8', 15:32:44 INFO - 'LANGUAGE': 'en_US:en', 15:32:44 INFO - 'LOGNAME': 'cltbld', 15:32:44 INFO - 'MAIL': '/var/mail/cltbld', 15:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:44 INFO - 'MOZ_NO_REMOTE': '1', 15:32:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:44 INFO - 'NO_EM_RESTART': '1', 15:32:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:44 INFO - 'PWD': '/builds/slave/test', 15:32:44 INFO - 'SHELL': '/bin/bash', 15:32:44 INFO - 'SHLVL': '1', 15:32:44 INFO - 'TERM': 'linux', 15:32:44 INFO - 'TMOUT': '86400', 15:32:44 INFO - 'USER': 'cltbld', 15:32:44 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:44 INFO - '_': '/tools/buildbot/bin/python'} 15:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:44 INFO - Running setup.py (path:/tmp/pip-x9CtVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:44 INFO - Running setup.py (path:/tmp/pip-mhnbEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:44 INFO - Running setup.py (path:/tmp/pip-adBpA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:44 INFO - Running setup.py (path:/tmp/pip-Yb65CK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:44 INFO - Running setup.py (path:/tmp/pip-jycAKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:44 INFO - Running setup.py (path:/tmp/pip-bMTWTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:44 INFO - Running setup.py (path:/tmp/pip-QHdWoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:44 INFO - Running setup.py (path:/tmp/pip-osYrFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:44 INFO - Running setup.py (path:/tmp/pip-w2zPO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:45 INFO - Running setup.py (path:/tmp/pip-enBdbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:45 INFO - Running setup.py (path:/tmp/pip-4mtXHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:45 INFO - Running setup.py (path:/tmp/pip-kflGWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:45 INFO - Running setup.py (path:/tmp/pip-ZlRLPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:45 INFO - Running setup.py (path:/tmp/pip-DRp1m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:45 INFO - Running setup.py (path:/tmp/pip-8Jf7YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:45 INFO - Running setup.py (path:/tmp/pip-tFlIQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:45 INFO - Running setup.py (path:/tmp/pip-eAI0Ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:32:45 INFO - Running setup.py install for manifestparser 15:32:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:32:45 INFO - Running setup.py install for mozcrash 15:32:45 INFO - Running setup.py install for mozdebug 15:32:45 INFO - Running setup.py install for mozdevice 15:32:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozfile 15:32:46 INFO - Running setup.py install for mozhttpd 15:32:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozinfo 15:32:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozInstall 15:32:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozleak 15:32:46 INFO - Running setup.py install for mozlog 15:32:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for moznetwork 15:32:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozprocess 15:32:46 INFO - Running setup.py install for mozprofile 15:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:32:46 INFO - Running setup.py install for mozrunner 15:32:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:32:47 INFO - Running setup.py install for mozscreenshot 15:32:47 INFO - Running setup.py install for moztest 15:32:47 INFO - Running setup.py install for mozversion 15:32:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:32:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:32:47 INFO - Cleaning up... 15:32:47 INFO - Return code: 0 15:32:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:47 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')]} 15:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:47 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')]} 15:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:47 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 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:47 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 15:32:47 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 15:32:47 INFO - Using env: {'DISPLAY': ':0', 15:32:47 INFO - 'HOME': '/home/cltbld', 15:32:47 INFO - 'LANG': 'en_US.UTF-8', 15:32:47 INFO - 'LANGUAGE': 'en_US:en', 15:32:47 INFO - 'LOGNAME': 'cltbld', 15:32:47 INFO - 'MAIL': '/var/mail/cltbld', 15:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:47 INFO - 'MOZ_NO_REMOTE': '1', 15:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:47 INFO - 'NO_EM_RESTART': '1', 15:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:47 INFO - 'PWD': '/builds/slave/test', 15:32:47 INFO - 'SHELL': '/bin/bash', 15:32:47 INFO - 'SHLVL': '1', 15:32:47 INFO - 'TERM': 'linux', 15:32:47 INFO - 'TMOUT': '86400', 15:32:47 INFO - 'USER': 'cltbld', 15:32:47 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:47 INFO - '_': '/tools/buildbot/bin/python'} 15:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:32:47 INFO - Running setup.py (path:/tmp/pip-ghKMkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:32:47 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)) 15:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:32:47 INFO - Running setup.py (path:/tmp/pip-rzNRwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:32:47 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)) 15:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:32:47 INFO - Running setup.py (path:/tmp/pip-5BxNuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:32:47 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)) 15:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:32:47 INFO - Running setup.py (path:/tmp/pip-aE79m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:32:47 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)) 15:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:32:47 INFO - Running setup.py (path:/tmp/pip-jBtook-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:32:48 INFO - Running setup.py (path:/tmp/pip-7R0dvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:32:48 INFO - Running setup.py (path:/tmp/pip-w3X789-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:32:48 INFO - Running setup.py (path:/tmp/pip-TnEmuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:32:48 INFO - Running setup.py (path:/tmp/pip-p74pDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:32:48 INFO - Running setup.py (path:/tmp/pip-gFTzMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:32:48 INFO - Running setup.py (path:/tmp/pip-LPNPGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:32:48 INFO - Running setup.py (path:/tmp/pip-YkV1OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:32:48 INFO - Running setup.py (path:/tmp/pip-DQ8NhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:32:48 INFO - Running setup.py (path:/tmp/pip-6PHoyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:48 INFO - Running setup.py (path:/tmp/pip-uylJVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:32:48 INFO - Running setup.py (path:/tmp/pip-NvBxym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:32:48 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)) 15:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:32:48 INFO - Running setup.py (path:/tmp/pip-uZ0lYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:32:48 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)) 15:32:48 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)) 15:32:48 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)) 15:32:48 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)) 15:32:48 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)) 15:32:48 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)) 15:32:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:50 INFO - Downloading blessings-1.6.tar.gz 15:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:32:50 INFO - Installing collected packages: blessings 15:32:50 INFO - Running setup.py install for blessings 15:32:50 INFO - Successfully installed blessings 15:32:50 INFO - Cleaning up... 15:32:51 INFO - Return code: 0 15:32:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:51 INFO - Reading from file tmpfile_stdout 15:32:51 INFO - Current package versions: 15:32:51 INFO - argparse == 1.2.1 15:32:51 INFO - blessings == 1.6 15:32:51 INFO - blobuploader == 1.2.4 15:32:51 INFO - docopt == 0.6.1 15:32:51 INFO - manifestparser == 1.1 15:32:51 INFO - mozInstall == 1.12 15:32:51 INFO - mozcrash == 0.17 15:32:51 INFO - mozdebug == 0.1 15:32:51 INFO - mozdevice == 0.48 15:32:51 INFO - mozfile == 1.2 15:32:51 INFO - mozhttpd == 0.7 15:32:51 INFO - mozinfo == 0.9 15:32:51 INFO - mozleak == 0.1 15:32:51 INFO - mozlog == 3.1 15:32:51 INFO - moznetwork == 0.27 15:32:51 INFO - mozprocess == 0.22 15:32:51 INFO - mozprofile == 0.28 15:32:51 INFO - mozrunner == 6.11 15:32:51 INFO - mozscreenshot == 0.1 15:32:51 INFO - mozsystemmonitor == 0.0 15:32:51 INFO - moztest == 0.7 15:32:51 INFO - mozversion == 1.4 15:32:51 INFO - psutil == 3.1.1 15:32:51 INFO - requests == 1.2.3 15:32:51 INFO - wsgiref == 0.1.2 15:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 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 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:51 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 15:32:51 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 15:32:51 INFO - Using env: {'DISPLAY': ':0', 15:32:51 INFO - 'HOME': '/home/cltbld', 15:32:51 INFO - 'LANG': 'en_US.UTF-8', 15:32:51 INFO - 'LANGUAGE': 'en_US:en', 15:32:51 INFO - 'LOGNAME': 'cltbld', 15:32:51 INFO - 'MAIL': '/var/mail/cltbld', 15:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:51 INFO - 'MOZ_NO_REMOTE': '1', 15:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:51 INFO - 'NO_EM_RESTART': '1', 15:32:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:51 INFO - 'PWD': '/builds/slave/test', 15:32:51 INFO - 'SHELL': '/bin/bash', 15:32:51 INFO - 'SHLVL': '1', 15:32:51 INFO - 'TERM': 'linux', 15:32:51 INFO - 'TMOUT': '86400', 15:32:51 INFO - 'USER': 'cltbld', 15:32:51 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:51 INFO - '_': '/tools/buildbot/bin/python'} 15:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 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)) 15:32:51 INFO - Cleaning up... 15:32:51 INFO - Return code: 0 15:32:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:32:51 INFO - Using env: {'DISPLAY': ':0', 15:32:51 INFO - 'HOME': '/home/cltbld', 15:32:51 INFO - 'LANG': 'en_US.UTF-8', 15:32:51 INFO - 'LANGUAGE': 'en_US:en', 15:32:51 INFO - 'LOGNAME': 'cltbld', 15:32:51 INFO - 'MAIL': '/var/mail/cltbld', 15:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:51 INFO - 'MOZ_NO_REMOTE': '1', 15:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:51 INFO - 'NO_EM_RESTART': '1', 15:32:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:51 INFO - 'PWD': '/builds/slave/test', 15:32:51 INFO - 'SHELL': '/bin/bash', 15:32:51 INFO - 'SHLVL': '1', 15:32:51 INFO - 'TERM': 'linux', 15:32:51 INFO - 'TMOUT': '86400', 15:32:51 INFO - 'USER': 'cltbld', 15:32:51 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:51 INFO - '_': '/tools/buildbot/bin/python'} 15:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:51 INFO - Downloading/unpacking jsonschema 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:32:53 INFO - Downloading/unpacking functools32 (from jsonschema) 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32: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 15:32:53 INFO - Downloading functools32-3.2.3-2.tar.gz 15:32:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:32:53 INFO - warning: no files found matching '*.txt' 15:32:53 INFO - no previously-included directories found matching 'build' 15:32:53 INFO - no previously-included directories found matching 'dist' 15:32:53 INFO - no previously-included directories found matching '.git*' 15:32:53 INFO - Installing collected packages: jsonschema, functools32 15:32:53 INFO - Running setup.py install for functools32 15:32:53 INFO - warning: no files found matching '*.txt' 15:32:53 INFO - no previously-included directories found matching 'build' 15:32:53 INFO - no previously-included directories found matching 'dist' 15:32:53 INFO - no previously-included directories found matching '.git*' 15:32:53 INFO - Successfully installed jsonschema functools32 15:32:53 INFO - Cleaning up... 15:32:54 INFO - Return code: 0 15:32:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:32:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95d0a71f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25745a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', '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 15:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:32:54 INFO - Using env: {'DISPLAY': ':0', 15:32:54 INFO - 'HOME': '/home/cltbld', 15:32:54 INFO - 'LANG': 'en_US.UTF-8', 15:32:54 INFO - 'LANGUAGE': 'en_US:en', 15:32:54 INFO - 'LOGNAME': 'cltbld', 15:32:54 INFO - 'MAIL': '/var/mail/cltbld', 15:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:32:54 INFO - 'MOZ_NO_REMOTE': '1', 15:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:32:54 INFO - 'NO_EM_RESTART': '1', 15:32:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:32:54 INFO - 'PWD': '/builds/slave/test', 15:32:54 INFO - 'SHELL': '/bin/bash', 15:32:54 INFO - 'SHLVL': '1', 15:32:54 INFO - 'TERM': 'linux', 15:32:54 INFO - 'TMOUT': '86400', 15:32:54 INFO - 'USER': 'cltbld', 15:32:54 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:32:54 INFO - '_': '/tools/buildbot/bin/python'} 15:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:32:54 INFO - Downloading/unpacking flake8 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:32:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:32:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:32:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:32:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:32:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:32:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:32:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:32:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:32:56 INFO - no previously-included directories found matching 'docs/_build' 15:32:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:32:56 INFO - Running setup.py install for pep8 15:32:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:32:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:32:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:32:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:32:56 INFO - no previously-included directories found matching 'docs/_build' 15:32:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:32:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:32:56 INFO - Cleaning up... 15:32:57 INFO - Return code: 0 15:32:57 INFO - Running post-action listener: _resource_record_post_action 15:32:57 INFO - Running post-action listener: _start_resource_monitoring 15:32:57 INFO - Starting resource monitoring. 15:32:57 INFO - ##### 15:32:57 INFO - ##### Running install step. 15:32:57 INFO - ##### 15:32:57 INFO - Running pre-action listener: _resource_record_pre_action 15:32:57 INFO - Running main action method: install 15:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:32:57 INFO - Reading from file tmpfile_stdout 15:32:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:32:57 INFO - Reading from file tmpfile_stdout 15:32:57 INFO - Output received: 15:32:57 INFO - Usage: mozinstall [options] installer 15:32:57 INFO - Options: 15:32:57 INFO - -h, --help show this help message and exit 15:32:57 INFO - -d DEST, --destination=DEST 15:32:57 INFO - Directory to install application into. [default: 15:32:57 INFO - "/builds/slave/test"] 15:32:57 INFO - --app=APP Application being installed. [default: firefox] 15:32:57 INFO - mkdir: /builds/slave/test/build/application 15:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:33:13 INFO - Reading from file tmpfile_stdout 15:33:13 INFO - Output received: 15:33:13 INFO - /builds/slave/test/build/application/firefox/firefox 15:33:13 INFO - Running post-action listener: _resource_record_post_action 15:33:13 INFO - ##### 15:33:13 INFO - ##### Running run-tests step. 15:33:13 INFO - ##### 15:33:13 INFO - Running pre-action listener: _resource_record_pre_action 15:33:13 INFO - Running main action method: run_tests 15:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:33:13 INFO - Python 2.7.3 15:33:13 INFO - Return code: 0 15:33:13 INFO - grabbing minidump binary from tooltool 15:33:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:33:13 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2603410>, '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 0x2604310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26047a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:33:13 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 15:33:13 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 15:33:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:33:13 INFO - Return code: 0 15:33:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:33:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:33:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:33:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:33:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:33:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:33:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:33:14 INFO - Return code: 0 15:33:14 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:33:14 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:33:14 INFO - Using env: {'DISPLAY': ':0', 15:33:14 INFO - 'HOME': '/home/cltbld', 15:33:14 INFO - 'LANG': 'en_US.UTF-8', 15:33:14 INFO - 'LANGUAGE': 'en_US:en', 15:33:14 INFO - 'LOGNAME': 'cltbld', 15:33:14 INFO - 'MAIL': '/var/mail/cltbld', 15:33:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:33:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:33:14 INFO - 'MOZ_NO_REMOTE': '1', 15:33:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:33:14 INFO - 'NO_EM_RESTART': '1', 15:33:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:33:14 INFO - 'PWD': '/builds/slave/test', 15:33:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:33:14 INFO - 'SHELL': '/bin/bash', 15:33:14 INFO - 'SHLVL': '1', 15:33:14 INFO - 'TERM': 'linux', 15:33:14 INFO - 'TMOUT': '86400', 15:33:14 INFO - 'USER': 'cltbld', 15:33:14 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145', 15:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:33:14 INFO - '_': '/tools/buildbot/bin/python'} 15:33:14 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:33:14 INFO - mozversion application_buildid: 20160420141806 15:33:14 INFO - mozversion application_changeset: 4feb4dd910a5a2d3061dbdd376a80975206819c6 15:33:14 INFO - mozversion application_display_name: Nightly 15:33:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:33:14 INFO - mozversion application_name: Firefox 15:33:14 INFO - mozversion application_remotingname: firefox 15:33:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:33:14 INFO - mozversion application_vendor: Mozilla 15:33:14 INFO - mozversion application_version: 48.0a1 15:33:14 INFO - mozversion platform_buildid: 20160420141806 15:33:14 INFO - mozversion platform_changeset: 4feb4dd910a5a2d3061dbdd376a80975206819c6 15:33:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:33:14 INFO - mozversion platform_version: 48.0a1 15:33:14 INFO - using testdate: 1461191594 15:33:14 INFO - actual date: 1461191594 15:33:14 INFO - starting webserver on 'localhost:50348' 15:33:14 INFO - SUITE-START | Running 2 tests 15:33:14 INFO - TEST-START | dromaeo_css 15:33:14 INFO - operating with platform_type : linux_ 15:33:14 INFO - Initialising browser for dromaeo_css test... 15:33:14 INFO - TEST-INFO | started process 22770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1GWkNF/profile http://localhost:50348/getInfo.html) 15:33:18 INFO - PROCESS | 22770 | 15:33:18 INFO - PROCESS | 22770 | (process:22840): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:33:18 INFO - PROCESS | 22770 | __metrics Screen width/height:1600/1200 15:33:18 INFO - PROCESS | 22770 | colorDepth:24 15:33:18 INFO - PROCESS | 22770 | Browser inner width/height: 1024/697 15:33:18 INFO - PROCESS | 22770 | __metrics 15:33:19 ERROR - PROCESS | 22770 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:33:20 INFO - TEST-INFO | 22770: exit 0 15:33:20 INFO - Browser initialized. 15:33:20 INFO - Running cycle 1/1 for dromaeo_css test... 15:33:20 INFO - TEST-INFO | started process 22891 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1GWkNF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:33:22 INFO - PROCESS | 22891 | 15:33:22 INFO - PROCESS | 22891 | (process:22951): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:33:32 INFO - PROCESS | 22891 | RSS: Main: 164696064 15:33:32 INFO - PROCESS | 22891 | 15:34:22 INFO - PROCESS | 22891 | 1461191662065 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] 15:36:33 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50348/tests/dromaeo/cssquery-ext.html) 15:36:34 INFO - PROCESS | 22891 | RSS: Main: 168620032 15:36:34 INFO - PROCESS | 22891 | 15:39:36 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-ext.html (next: http://localhost:50348/tests/dromaeo/cssquery-jquery.html) 15:39:37 INFO - PROCESS | 22891 | RSS: Main: 165990400 15:39:37 INFO - PROCESS | 22891 | 15:42:38 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50348/tests/dromaeo/cssquery-mootools.html) 15:42:39 INFO - PROCESS | 22891 | RSS: Main: 167014400 15:42:39 INFO - PROCESS | 22891 | 15:45:41 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50348/tests/dromaeo/cssquery-prototype.html) 15:45:42 INFO - PROCESS | 22891 | RSS: Main: 164634624 15:45:42 INFO - PROCESS | 22891 | 15:48:44 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50348/tests/dromaeo/cssquery-yui.html) 15:48:45 INFO - PROCESS | 22891 | RSS: Main: 166174720 15:48:45 INFO - PROCESS | 22891 | 15:51:47 INFO - PROCESS | 22891 | Cycle 1(1): loaded http://localhost:50348/tests/dromaeo/cssquery-yui.html (next: http://localhost:50348/tests/dromaeo/cssquery-dojo.html) 15:51:48 INFO - PROCESS | 22891 | RSS: Main: 168017920 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | __start_tp_report 15:51:48 INFO - PROCESS | 22891 | _x_x_mozilla_page_load 15:51:48 INFO - PROCESS | 22891 | _x_x_mozilla_page_load_details 15:51:48 INFO - PROCESS | 22891 | |i|pagename|runs| 15:51:48 INFO - PROCESS | 22891 | |0;dojo.html;2573.395252115753;2637.4197676511167;2641.062422839893;2673.358393985444;2680.678318601767;4629.745364004978;4649.442066951966;4658.021815418762;4705.223638099718;4724.1260366832075;4606.170889239936;4666.580007799297;4699.318598803172;4739.218029025206;4743.739094349809;4289.914201715972;4430.889227769299;4434.600885920267;4440.333949907507;4441.378207051015;567.1421869634677;4317.892052698692;4332.891677708067;4420.4253447052;4441.75570343631;4386.802593883251;4401.185780630562;4404.986853023823;4406.339049142623;4443.755593442359;4194.790260486963;4474.865754027384;4478.193925093482;4489.326601009842;4522.706024108423;4278.42241297424;4312.61186493214;4335.674824388184;4353.695241333108;4354.738715677069;4600.539946005406;4601.493835678073;4606.792694328763;4678.625709943196;4696.741679207642;5004.324416203803;5021.623378246646;5026.5727413169925;5026.899462010776;5089.694618322913;5055.266986287004;5062.139436295838;5064.594832413398;5074.974625126888;5086.593072554187;4676.55572720591;4716.834910778106;4717.7169369837575;4747.0505898820375;4760.523947605281;5031.270465782441;5118.10433174193;5154.149565321755;5183.870403239949;5208.557272631793;4959.628027897922;4961.2806143109765;4987.426445958688;4989.201727723546;5026.54761071498;5028.974855125701;5047.848564543069;5059.595232381401;5069.163588008011;5072.391313042459;4872.731999740048;4988.7505624718615;5024.698518088926;5030.0191462664425;5086.796528138907;4758.1435341638835;4952;4963.255511673278;4970.304157417964;5013.323201367842;4164.562720914321;4193.266178418765;4206.369044643328;4228.344606585984;4229.2598795210715;3627.256412435497;3711.6081469448955;3742.3076730804887;3765.585785563586;3783.5838057813617;4301.182775272689;4334;4344.348347747863;4370.278903980808;4373.059792144704;4984.028114517634;4986.276989836453;5001.149804533238;5011.924821127686;5029.823956161516;4347.608715215646;4348.412964249849;4353.564643535621;4362.781860906942;4395.120975804852;4463.687541872103;4472.418585583853;4501.707389019703;4505.144022635689;4554.612857907049;4711.034237981137;4759.428868535729;4760.690555113906;4782.115308667769;4790.065937142223;4984;5006.874828129326;5012.22310541852;5016.4231113420965;5019.824306149268;4714.292856071616;4724.881877953078;4725.031368569366;4726.291056341576;4773.949731059161;4751.501092385388;4958.033183529177;4967.552920237052;5003.799848006039;5017.02168077236;5006.848424862229;5010.924836127534;5025.648204625787;5033.370828646419;5049.747512624427;4750.382450281441;4881.8535443936735;4890.364252647133;4894.706317620954;4943.332650092191;4815.975920120377;4821.710697358169;4846;4859.343988561641;4885.975570122127;4673.415823022122;4714.457837348612;4805.567498925114;4853.150698627796;4873.293372461044;4742.146413645577;4879.512048795092;4889.413270407574;4892.510748925079;4943.937053533507;4852.539008794159;4873.049755297683;4896.11869863428;4913.533214344631;4948.183549714401 15:51:48 INFO - PROCESS | 22891 | |1;ext.html;551.6371803459551;587.9353271140175;603.7313395538985;609.2932198649568;627.7991042866284;34716.305673886505;36002.63997360026;36040.81979590105;36102;36112.81943590281;36067.819660901725;37027.44458833113;37046.8147659262;37089.072773180684;37188.628113718856;7578.696852125909;7803.765887023398;7807.609619519001;7812.531248125102;7830.530168189898;7656.770296891103;7882.842343153134;7984.720534781284;7987.760367188965;7998;7960.203979602052;8016;8016.919830801705;8031.9598402007905;8043.517388956652;7905.407094467938;7954.602269886512;7979.962604861331;7997.720079797208;8004.559749213791;7820.569868657221;7891.289783919475;7906.802329941797;7920.564368959705;7959.96020019902;28950.565741513896;33220.833895830365;33860.49209261863;33877.49183762245;33924.321513569834;3070.815751054944;3094.6131733533307;3221.951670724941;3258.9348213035605;3342.1644588852787;27402.862985685144;28604.85697571499;29499.70500294991;29689.257768555744;30725.231869203224;3461.671141241578;3505.2989402119424;3589.766665166782;3699.3896007158787;3733.0480727414338;237292;241212;242147;243496.99999999822;244795;33900.830495847855;34427.31145377128;34588.65411345854;57623.42376576181;58190;33808.323833523194;33827.4925876112;33880;33908;34001.14997125092;12385.814212786816;12640.431180596712;12650.557230496888;12671.556495522613;12701.555445559361;10089.344192627455;10122.645707400206;10163.593456261815;10224.335418197794;10242.027007434282;8291.626876790559;8308.958455207685;8377.078521362768;8382.413231073888;8427.494350338999;3276.5576647152807;3301.422251106095;3319.6016478022793;3355.1444381682513;3382.4926261061037;902.4540153207215;905.108468158863;918.3158546882615;979.3927764785878;1005.4771518810178;27601.447971040467;28156;28920.710792892223;29188.854055729586;29305.706942930723;985.4383001688968;1009.4902074452501;1066.6746642274181;1085.9629054253328;1132.3828513460185;15017.324220410108;15235.771463428058;15562.688746225012;15713.450029248921;15827.841721582867;350390;472891;481326;484355;485212;46018.53981460143;46488;46947.76526117416;47032.294515582296;47149;1153.9538418463335;1258.3016425883723;1260.4517035089766;1261.369315342329;1267.4169881854266;28293.43413131767;29293.70706292995;29360.706392935797;29813;30160.24599385033;43764.781176093915;44369.556304437836;44640;44807.77596111999;44845.775771122244;326363;326496;328129;328488;329625;26046.869765651052;26263.606045908946;26572.734272657028;26949.461010780065;26997.595036074083;42107.57892421159;43096.35355469608;43422;43740;43867.341989869536;37216.44175337426;37281.81359093296;37660.62339376571;37717;37999.24001520009;36025.27949441049;36546.451803222444;37144.814275928446;37275.62724372721;37476.2504749898;31918.52122218122;36302.27395452129;36598.817005915866;37027.444588330654;37487.81256093702;29591.408171836872;31205.687943121186;31497.685023150392;31633.841830790698;32164.51753223657;24416.145434909693;24455.266342009767;24899.87550062238;25172.496550069263;25441.49117017686 15:51:48 INFO - PROCESS | 22891 | |2;jquery.html;369.27253310977363;369.8816378758797;369.95930447650755;382.134465435788;389.89083056744124;15389.692206155883;16232.107234102119;17360.47918562442;17365.131743412843;17454.127293635316;15693.843061569381;16121.838781612181;17103.657926841457;17138.31446742129;17196.742048869277;5099.311592934943;5248.8687782805355;5259.658122222043;5273.182656688219;5288.524032837044;3681.6134305897895;5159.329287192679;5703.116017017369;5987.6108052976415;6027.939720602803;5944.88110237795;5958.165856780054;5972.820815375546;5974.253218347707;5977.731002104916;5719.571032172583;5921.940780592225;5928.199693041427;5944.10838374243;5958.553108516879;5069.366329208849;5122.33409656746;5162.974185129088;5178.689278643294;5232.424433312332;19937.401877943685;20138.89930550338;20212.19151233949;20416.489587760425;20439.795602043938;1802.4592622213415;1929.8236883618445;1972.7632684078003;2224.1881713174735;2455.8580260178924;12864.29246391448;13239.470421183141;13358.732825343439;13422.798658020138;13494.730105397935;1717.3731587970426;1729.483433212825;1787.6156626325403;1802.044916194419;1805.1154934082206;64995.35004649987;65040.349596503904;65417;65533.67233163851;65946.3405365945;8959.328050396247;9106.81786364269;9322.161005509537;9516.666916657883;9624.807503849885;12403.813942790865;12428;12469.251844889335;12477.5008999639;12510.061745369134;6720.630365329856;6765.8646827063185;6818.28408017161;6969.059177011039;7030.683619237147;6250.406211409909;6375.234971803413;6449.838754031093;6475.190601174853;6534.215894092739;5728.427157284238;5989.251343582052;6014.909776353358;6036.913355595947;6121.602095863755;1601.5115389806222;1602.7115119278408;1640.811306699721;1757.9824201758074;1766.5495298699007;879.2921698033144;896.4262871762078;969.8254314223508;987.7975015121883;997.7206382212992;15415.229238538028;16530.83469165293;16686.916565417094;16912.661746764996;17166.05586692719;902.4134312696668;919.8988111307751;922.8292765838341;973.5132433783108;977.2848650618262;9567.282453815991;9578.425294482353;9607.663731769355;9703.805923881482;9814.754631134136;351360;355168;357148;357467;358382;50358;65573.67213163903;68469.3153068463;68708.31291687119;70375.64812175906;953.7138858342609;959.0170075672352;973.3332667123043;975.0493269062749;983.2036050798732;14885.251442745173;14945.701085978437;14951;15012;15223.162726050174;30033.249168770955;30760.230994225323;32488.675113248566;32811;35549.111272218404;44625;45550;48818.75590622024;48871.266930996775;49113.508864910895;7257.52826066304;7570;7809.219078092373;7819.804504887424;7857.253560911704;6612.768553100618;9399.248060155307;10115.887252402083;10872.565097396016;11189.272697274977;9939.30424870281;10309.48452577383;10464.215183861088;10528.684139475828;10714.571417143226;9877.901220987698;10038.548265327932;10060.295779295378;10233.232507562112;10359.741006474898;10597.311174773615;10621.522031508755;10655.467226638482;10730.302530335503;10747.677569672922;7812.125329709819;7817.570033648204;7989.400794940519;8039.959800201195;8046.235607617267;10065.094141527297;10122.342047766871;10182.796344073226;10343.224258180517;10441 15:51:48 INFO - PROCESS | 22891 | |3;mootools.html;1443.9061461005033;1469.1111877314224;1602.4791942618644;1766.6908291049062;1790.9283628654846;6674.466042716583;7346.926530734704;7424.220456852027;7706.961465192673;7745.651445684936;7511.887321690179;7527.7741667749915;7579;7580.583067931276;7585.1656317805;2196.461866842618;2217.090992692996;2222.7221597300336;2222.8107962240206;2224.1992882562263;2217.2018073493527;2227.164813195052;2231.241377931503;2240.182333448288;2246.8314876384256;2240.1487434774763;2245.158065725353;2258.4128126687015;2261.231181398324;2263.909443622253;2152.817010554105;2159.265849611132;2162.2756376613775;2165.4911095892453;2179.2154824263253;2113.3131732187103;2117.1637203304763;2117.7035215069905;2124.936251912445;2125.5430082532175;7655.004849369547;7670.616469176574;7720.691172353099;7721.575313357763;7726.266004729542;4438.533953934822;4582.816687332503;4584.610308123813;4587.954120458786;4598.678092533524;2865.8137221080565;2912.3301640622562;2934.5158048921903;2968.2876109733725;3031.1967328657925;953.8759961204998;968.6125549780074;979.6424305128648;992.4987881120061;1003.2976916158718;10456.32033917793;10491.370517768959;10536.578536858517;10541.525631346607;10555.73610659732;4167.5207351154395;4171.3325867860995;4330.198913201068;4385.978070109629;4389.231884420214;9002.099790020946;9009.63961441535;9024.187823095954;9058.954705226432;9079.137481939206;4628.375169352094;4637.956459796586;4674.929876051862;4679.906401872012;4689.50760170184;3604.675579197832;3987.860424885115;4117.91764164715;4236.576342365739;4239.957600423956;3608.783472991629;3624.5469316335457;3700.500432441586;3728.2357116790995;3735.34631439497;1179.9469023893946;1192.0940085534883;1195.7010747313172;1197.9401029948467;1209.9213551119326;851.1190917400585;865.3250464637592;906.3972458315278;911.0343036381324;923.5613083785148;5444.972775136178;5453.890922181534;5473.452654734574;5504.944950550524;5632.577556683265;425.6573458366045;433.24182680309457;437.11920480232357;448.1619371774802;448.4663250731619;3830.444585535082;3861.7296789225047;3895.162540053902;3926.9607303926596;4222.556631553704;8985.550722463851;9115.99724030356;9166.954165229132;9218.308626853013;9247.861282080774;9026.007139214682;9033.50315732641;9075.954620226856;9095.681651142178;9118.498482583522;7022.40309573692;7130.322619351257;7247.9637601811655;7298.452616053711;7326.82770756687;5707.743151558273;5724.141378793214;5749.9712501437225;5783.219265399285;5940.910886336622;8797.340199484936;8812.735617931472;8994.370394072352;9085.954570227106;9130.543472826199;8809.471431714117;9016.684416045407;9032.683856065007;9061.54692265371;9089.77275568116;6049.455549000611;6509.218893732593;6797.456203503609;6802.761903333359;6921.861562768616;7413.369863561657;7415.962920185365;7430.517016393917;7465.3654439371085;7494.100707914867;6605;6783.626900520321;6785.864282714219;6804.183497980274;6934.653267336714;6701.564398314094;6703.698333575098;6730.562513436611;6742.258351581224;6920.515563910478;6140.631562106288;6490.091387205889;6526.510511711545;6535;6552.770653027121;4827.106985207748;4832.855014349575;4869.123557759637;4921.434035086011;4983.327250821238;4664.300354946785;4709.270063140219;4719.339292499121;4754.334393185026;4815.012922350839 15:51:48 INFO - PROCESS | 22891 | |4;prototype.html;304.3031457961269;306.55090292721167;308.4078569147237;313.94505961456724;315.65278193986603;5206.531412172903;5278.287431196787;5317.78728850846;5323.653962492435;5338.439463856293;5193.16909294513;5214.191800270964;5223.112070947938;5227.634065615409;5250.789968401269;3826.827792749319;3894.1432884765304;4059.7564146151176;4072.470578824749;4090.754554726726;4034.838606455753;4054.574269701683;4081.979590102045;4084.89787755307;4098.118904435546;4045.7370270932443;4064.9593504065024;4066.674666026711;4067.2475592015426;4072.6741860651223;3798.373268410709;3987.521497420328;4013.3979903014488;4031.2340655275552;4044.170944956292;3477.2871561329866;3486.4770284457536;3499.7900125992523;3501.9474707879403;3502.369573476773;4985.900281994376;4997.175466048097;4999.075171093359;5005.424376196747;5040.36995375578;4354.629856462205;4578.656600754924;4604.88487787808;4622.86131416058;4638.628909687217;5474.479924407175;5507;5507.366652834935;5507.669539827583;5516.558675306006;3813.5127300352883;3816.580176180618;3831.7700937943814;3833.195029043904;3841.078141246081;5540.833774986797;5741.167530708066;5783.2192653992015;5798.739056742456;5833.912491312633;3795.69634429245;3834.539855217336;3851;3859.054531639766;3874.341361968472;3877.651011408987;3885.572587015426;3901.7073719471155;3921.9019524512114;3933.6459718624888;4541.863744087683;4558.430196225472;4558.49856515783;4562.703424277412;4575.084983003413;5128.589712822965;5496.0931446310915;5497.697626630574;5506.64206826563;5542.11326187808;1360.6666366740212;1377.1736957825224;1390.64538542673;1393.470481217131;1393.540131756518;853.1638993786124;888.684516996457;901.1709227510574;923.7690577355661;926.6200857648332;4741.881452963704;4758.666893317504;4771.188897887368;4808.422989241313;4818;5617.887642247132;5634.6337488063145;5647.040003199466;5655.822508334321;5673.149027645886;4634.513376095461;4723.5984941279685;4742.881427964259;4753.786079626425;4791.568758811654;2996.8801247950273;3029.33354661974;3038.3619439917425;3042.756579473637;3052.8168309901475;5295.311609490742;5340.91988620171;5346.97326513365;5362.222477740784;5363.275957745733;5666.178404131378;5802.4197580241635;5806.767729290781;5815.3021637402935;5818.301803783488;5269.815556455505;5304.098303288451;5319.654222475527;5321.201819727072;5361.919571206435;5474.972625136849;5609.32688077417;5651.321841379061;5652.717364131695;5662.490375866192;5523.502884740393;5540;5554.888902222014;5561.749721262634;5574.442555744393;5314.202869569595;5354.839354819362;5356.250124982584;5363.115086010765;5368.785248590013;3711.9072023199637;3862.806859657062;3872.3417019106255;3873.9418908716957;3882.902927426837;5602.607817452739;5607.411221821813;5612.943870561242;5633.464820842013;5642.689652069175;5415.566754659697;5418.972905135449;5428.592855535771;5459.181122831607;5465.480779325958;5333.573314134937;5399.379071406838;5434.483724046209;5441.347038355423;5465.890682186414;5297.8145764898045;5338.065838478328;5349.866253343697;5352.652077614943;5366.7316634167355;5285.735713214247;5438.443931352258;5630.774769009357;5630.88738225225;5667.3482549507335;5443.918341224964;5458.208559758892;5479.479449452296;5487.945120548743;5491.862703432446 15:51:48 INFO - PROCESS | 22891 | |5;yui.html;671.0270108342904;725.87297222986;731.7475470962522;754.7433872483356;794.9046114466265;12176.208546444475;12252.81620775689;12346.62960111197;12378.133530652858;12425.937870310647;11414.258073225235;11492.942535287333;13262.336883155851;13940.860591394083;13995;806.8466991271654;812.7886749445157;813.9511629302232;814.7881550796776;815.7185770909027;645.286957911508;787.0830482487895;808.3694718119876;808.7654580171743;809.5102463009882;850.9106543812903;852.3607294529103;852.7782776478099;853.4537895746727;857.2284943550792;830.6303694855792;837.7528629054443;844.3118858130604;845.4504572028169;847.4195176304221;622.7135517661835;623.4513628007325;623.847157446424;623.8689875126229;625.0436831647542;13327.600171994856;13391.598252052356;13392.129511581814;13396.598102056954;13412.79880801779;12717.745645087047;12753.936230318881;12767.680807979781;12906.161099528594;13059.673508162277;9834.114929656294;9975.950120249425;10083.19334453242;10090.243231757648;10398.324108932895;3260.96739032609;3298.818564978925;3335.6164041135094;3337.93324133516;3366.4950257461333;47335.52664473346;47461.288080678816;47623.047539049025;47729.28406073877;48087.278690819665;9954.800903981879;10072.848907266396;10257.33327333721;10396.584136634601;10583.153347732163;17490.300387984593;17499;17570;17601;17635.118244087746;6939.340762627542;7028.894566581505;7060.223375428699;7079.681414336367;7092.219855815959;3909.550401703784;3919.6080391960577;3958.960410395917;3961.960380396217;3984.2828290907914;2935.3688956874375;2972.1380799568315;2988.5965394671875;2998.7151220634005;3001.564773107887;504.4400715206118;507.1631807517552;512.3518748782793;513.850983214871;518.409013724347;629.6662768732579;639.9232092148878;641.3394203969918;645.7417033186763;666.1107421592131;7815.823954643429;7857.253560911704;8025.799355016171;8176.1006289308125;8497.490150590984;493.2921257994789;601.6600620649319;618.6552964265384;626.3392121311969;647.8412788866664;3766.6233376662112;3909.4722212501515;3940.5074365704286;3947.782871942071;3970.6029397060065;50778.49221507811;51816.22275665868;51829.222561661605;52343.21485177725;52474.47525524772;44370.55629443729;45267.77366113148;45443.54556454459;45533;45705.31442028372;41685.79157104195;41899;42309.15381692349;42647.786761065996;42991.78504107522;7611.695532178651;7645.1972542882195;7660.502067365604;7837.37301015906;7840.84318313642;28266.85866570654;28274.575881361383;29042.564361534172;29265.561016585194;29472.55791163177;36003.45994810027;38145;38343;38420;38487.807560962014;9523.333366664267;9692.660756873758;9700.320977531504;9707.271954603575;10965.067969222386;10770.461476926279;10791.460426978776;11605.6518304451;11823.645290641294;12191.451384687887;10154.847677284699;10376.533056012346;10377.858435572232;10409.42748148859;10816.4050977197;10748.83876741865;11200.21598488098;11271.380074096003;11640.476178572155;11661.76676466449;10583.417912014605;10752.623658171926;11056.889431105908;11456.82814757796;11460.598879039193;9920.851187232052;9976.501174941079;10032.598696052077;10262.948685256526;10388.168946484111;9341.439513629204;9750.122488976027;9923.236092434172;9925.851112233178;9983.550740216853 15:51:48 INFO - PROCESS | 22891 | __end_tp_report 15:51:48 INFO - PROCESS | 22891 | __start_cc_report 15:51:48 INFO - PROCESS | 22891 | _x_x_mozilla_cycle_collect,359 15:51:48 INFO - PROCESS | 22891 | __end_cc_report 15:51:48 INFO - PROCESS | 22891 | __startTimestamp1461192708172__endTimestamp 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | ------- Summary: start ------- 15:51:48 INFO - PROCESS | 22891 | Number of tests: 6 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#0] dojo.html Cycles:165 Average:4625.59 Median:4751.50 stddev:573.01 (12.1%) stddev-sans-first:551.69 15:51:48 INFO - PROCESS | 22891 | Values: 2573.4 2637.4 2641.1 2673.4 2680.7 4629.7 4649.4 4658.0 4705.2 4724.1 4606.2 4666.6 4699.3 4739.2 4743.7 4289.9 4430.9 4434.6 4440.3 4441.4 567.1 4317.9 4332.9 4420.4 4441.8 4386.8 4401.2 4405.0 4406.3 4443.8 4194.8 4474.9 4478.2 4489.3 4522.7 4278.4 4312.6 4335.7 4353.7 4354.7 4600.5 4601.5 4606.8 4678.6 4696.7 5004.3 5021.6 5026.6 5026.9 5089.7 5055.3 5062.1 5064.6 5075.0 5086.6 4676.6 4716.8 4717.7 4747.1 4760.5 5031.3 5118.1 5154.1 5183.9 5208.6 4959.6 4961.3 4987.4 4989.2 5026.5 5029.0 5047.8 5059.6 5069.2 5072.4 4872.7 4988.8 5024.7 5030.0 5086.8 4758.1 4952.0 4963.3 4970.3 5013.3 4164.6 4193.3 4206.4 4228.3 4229.3 3627.3 3711.6 3742.3 3765.6 3783.6 4301.2 4334.0 4344.3 4370.3 4373.1 4984.0 4986.3 5001.1 5011.9 5029.8 4347.6 4348.4 4353.6 4362.8 4395.1 4463.7 4472.4 4501.7 4505.1 4554.6 4711.0 4759.4 4760.7 4782.1 4790.1 4984.0 5006.9 5012.2 5016.4 5019.8 4714.3 4724.9 4725.0 4726.3 4773.9 4751.5 4958.0 4967.6 5003.8 5017.0 5006.8 5010.9 5025.6 5033.4 5049.7 4750.4 4881.9 4890.4 4894.7 4943.3 4816.0 4821.7 4846.0 4859.3 4886.0 4673.4 4714.5 4805.6 4853.2 4873.3 4742.1 4879.5 4889.4 4892.5 4943.9 4852.5 4873.0 4896.1 4913.5 4948.2 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#1] ext.html Cycles:180 Average:47841.53 Median:192553.15 stddev:93941.06 (48.8%) stddev-sans-first:94137.49 15:51:48 INFO - PROCESS | 22891 | Values: 551.6 587.9 603.7 609.3 627.8 34716.3 36002.6 36040.8 36102.0 36112.8 36067.8 37027.4 37046.8 37089.1 37188.6 7578.7 7803.8 7807.6 7812.5 7830.5 7656.8 7882.8 7984.7 7987.8 7998.0 7960.2 8016.0 8016.9 8032.0 8043.5 7905.4 7954.6 7980.0 7997.7 8004.6 7820.6 7891.3 7906.8 7920.6 7960.0 28950.6 33220.8 33860.5 33877.5 33924.3 3070.8 3094.6 3222.0 3258.9 3342.2 27402.9 28604.9 29499.7 29689.3 30725.2 3461.7 3505.3 3589.8 3699.4 3733.0 237292.0 241212.0 242147.0 243497.0 244795.0 33900.8 34427.3 34588.7 57623.4 58190.0 33808.3 33827.5 33880.0 33908.0 34001.1 12385.8 12640.4 12650.6 12671.6 12701.6 10089.3 10122.6 10163.6 10224.3 10242.0 8291.6 8309.0 8377.1 8382.4 8427.5 3276.6 3301.4 3319.6 3355.1 3382.5 902.5 905.1 918.3 979.4 1005.5 27601.4 28156.0 28920.7 29188.9 29305.7 985.4 1009.5 1066.7 1086.0 1132.4 15017.3 15235.8 15562.7 15713.5 15827.8 350390.0 472891.0 481326.0 484355.0 485212.0 46018.5 46488.0 46947.8 47032.3 47149.0 1154.0 1258.3 1260.5 1261.4 1267.4 28293.4 29293.7 29360.7 29813.0 30160.2 43764.8 44369.6 44640.0 44807.8 44845.8 326363.0 326496.0 328129.0 328488.0 329625.0 26046.9 26263.6 26572.7 26949.5 26997.6 42107.6 43096.4 43422.0 43740.0 43867.3 37216.4 37281.8 37660.6 37717.0 37999.2 36025.3 36546.5 37144.8 37275.6 37476.3 31918.5 36302.3 36598.8 37027.4 37487.8 29591.4 31205.7 31497.7 31633.8 32164.5 24416.1 24455.3 24899.9 25172.5 25441.5 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#2] jquery.html Cycles:180 Average:22716.83 Median:48992.39 stddev:58573.89 (119.6%) stddev-sans-first:58714.17 15:51:48 INFO - PROCESS | 22891 | Values: 369.3 369.9 370.0 382.1 389.9 15389.7 16232.1 17360.5 17365.1 17454.1 15693.8 16121.8 17103.7 17138.3 17196.7 5099.3 5248.9 5259.7 5273.2 5288.5 3681.6 5159.3 5703.1 5987.6 6027.9 5944.9 5958.2 5972.8 5974.3 5977.7 5719.6 5921.9 5928.2 5944.1 5958.6 5069.4 5122.3 5163.0 5178.7 5232.4 19937.4 20138.9 20212.2 20416.5 20439.8 1802.5 1929.8 1972.8 2224.2 2455.9 12864.3 13239.5 13358.7 13422.8 13494.7 1717.4 1729.5 1787.6 1802.0 1805.1 64995.4 65040.3 65417.0 65533.7 65946.3 8959.3 9106.8 9322.2 9516.7 9624.8 12403.8 12428.0 12469.3 12477.5 12510.1 6720.6 6765.9 6818.3 6969.1 7030.7 6250.4 6375.2 6449.8 6475.2 6534.2 5728.4 5989.3 6014.9 6036.9 6121.6 1601.5 1602.7 1640.8 1758.0 1766.5 879.3 896.4 969.8 987.8 997.7 15415.2 16530.8 16686.9 16912.7 17166.1 902.4 919.9 922.8 973.5 977.3 9567.3 9578.4 9607.7 9703.8 9814.8 351360.0 355168.0 357148.0 357467.0 358382.0 50358.0 65573.7 68469.3 68708.3 70375.6 953.7 959.0 973.3 975.0 983.2 14885.3 14945.7 14951.0 15012.0 15223.2 30033.2 30760.2 32488.7 32811.0 35549.1 44625.0 45550.0 48818.8 48871.3 49113.5 7257.5 7570.0 7809.2 7819.8 7857.3 6612.8 9399.2 10115.9 10872.6 11189.3 9939.3 10309.5 10464.2 10528.7 10714.6 9877.9 10038.5 10060.3 10233.2 10359.7 10597.3 10621.5 10655.5 10730.3 10747.7 7812.1 7817.6 7989.4 8040.0 8046.2 10065.1 10122.3 10182.8 10343.2 10441.0 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#3] mootools.html Cycles:180 Average:5131.96 Median:5214.15 stddev:2807.67 (53.8%) stddev-sans-first:2801.86 15:51:48 INFO - PROCESS | 22891 | Values: 1443.9 1469.1 1602.5 1766.7 1790.9 6674.5 7346.9 7424.2 7707.0 7745.7 7511.9 7527.8 7579.0 7580.6 7585.2 2196.5 2217.1 2222.7 2222.8 2224.2 2217.2 2227.2 2231.2 2240.2 2246.8 2240.1 2245.2 2258.4 2261.2 2263.9 2152.8 2159.3 2162.3 2165.5 2179.2 2113.3 2117.2 2117.7 2124.9 2125.5 7655.0 7670.6 7720.7 7721.6 7726.3 4438.5 4582.8 4584.6 4588.0 4598.7 2865.8 2912.3 2934.5 2968.3 3031.2 953.9 968.6 979.6 992.5 1003.3 10456.3 10491.4 10536.6 10541.5 10555.7 4167.5 4171.3 4330.2 4386.0 4389.2 9002.1 9009.6 9024.2 9059.0 9079.1 4628.4 4638.0 4674.9 4679.9 4689.5 3604.7 3987.9 4117.9 4236.6 4240.0 3608.8 3624.5 3700.5 3728.2 3735.3 1179.9 1192.1 1195.7 1197.9 1209.9 851.1 865.3 906.4 911.0 923.6 5445.0 5453.9 5473.5 5504.9 5632.6 425.7 433.2 437.1 448.2 448.5 3830.4 3861.7 3895.2 3927.0 4222.6 8985.6 9116.0 9167.0 9218.3 9247.9 9026.0 9033.5 9076.0 9095.7 9118.5 7022.4 7130.3 7248.0 7298.5 7326.8 5707.7 5724.1 5750.0 5783.2 5940.9 8797.3 8812.7 8994.4 9086.0 9130.5 8809.5 9016.7 9032.7 9061.5 9089.8 6049.5 6509.2 6797.5 6802.8 6921.9 7413.4 7416.0 7430.5 7465.4 7494.1 6605.0 6783.6 6785.9 6804.2 6934.7 6701.6 6703.7 6730.6 6742.3 6920.5 6140.6 6490.1 6526.5 6535.0 6552.8 4827.1 4832.9 4869.1 4921.4 4983.3 4664.3 4709.3 4719.3 4754.3 4815.0 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#4] prototype.html Cycles:180 Average:4524.12 Median:5239.21 stddev:1338.35 (25.5%) stddev-sans-first:1304.09 15:51:48 INFO - PROCESS | 22891 | Values: 304.3 306.6 308.4 313.9 315.7 5206.5 5278.3 5317.8 5323.7 5338.4 5193.2 5214.2 5223.1 5227.6 5250.8 3826.8 3894.1 4059.8 4072.5 4090.8 4034.8 4054.6 4082.0 4084.9 4098.1 4045.7 4065.0 4066.7 4067.2 4072.7 3798.4 3987.5 4013.4 4031.2 4044.2 3477.3 3486.5 3499.8 3501.9 3502.4 4985.9 4997.2 4999.1 5005.4 5040.4 4354.6 4578.7 4604.9 4622.9 4638.6 5474.5 5507.0 5507.4 5507.7 5516.6 3813.5 3816.6 3831.8 3833.2 3841.1 5540.8 5741.2 5783.2 5798.7 5833.9 3795.7 3834.5 3851.0 3859.1 3874.3 3877.7 3885.6 3901.7 3921.9 3933.6 4541.9 4558.4 4558.5 4562.7 4575.1 5128.6 5496.1 5497.7 5506.6 5542.1 1360.7 1377.2 1390.6 1393.5 1393.5 853.2 888.7 901.2 923.8 926.6 4741.9 4758.7 4771.2 4808.4 4818.0 5617.9 5634.6 5647.0 5655.8 5673.1 4634.5 4723.6 4742.9 4753.8 4791.6 2996.9 3029.3 3038.4 3042.8 3052.8 5295.3 5340.9 5347.0 5362.2 5363.3 5666.2 5802.4 5806.8 5815.3 5818.3 5269.8 5304.1 5319.7 5321.2 5361.9 5475.0 5609.3 5651.3 5652.7 5662.5 5523.5 5540.0 5554.9 5561.7 5574.4 5314.2 5354.8 5356.3 5363.1 5368.8 3711.9 3862.8 3872.3 3873.9 3882.9 5602.6 5607.4 5612.9 5633.5 5642.7 5415.6 5419.0 5428.6 5459.2 5465.5 5333.6 5399.4 5434.5 5441.3 5465.9 5297.8 5338.1 5349.9 5352.7 5366.7 5285.7 5438.4 5630.8 5630.9 5667.3 5443.9 5458.2 5479.5 5487.9 5491.9 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - PROCESS | 22891 | [#5] yui.html Cycles:180 Average:12791.36 Median:43681.17 stddev:14348.42 (32.8%) stddev-sans-first:14359.80 15:51:48 INFO - PROCESS | 22891 | Values: 671.0 725.9 731.7 754.7 794.9 12176.2 12252.8 12346.6 12378.1 12425.9 11414.3 11492.9 13262.3 13940.9 13995.0 806.8 812.8 814.0 814.8 815.7 645.3 787.1 808.4 808.8 809.5 850.9 852.4 852.8 853.5 857.2 830.6 837.8 844.3 845.5 847.4 622.7 623.5 623.8 623.9 625.0 13327.6 13391.6 13392.1 13396.6 13412.8 12717.7 12753.9 12767.7 12906.2 13059.7 9834.1 9976.0 10083.2 10090.2 10398.3 3261.0 3298.8 3335.6 3337.9 3366.5 47335.5 47461.3 47623.0 47729.3 48087.3 9954.8 10072.8 10257.3 10396.6 10583.2 17490.3 17499.0 17570.0 17601.0 17635.1 6939.3 7028.9 7060.2 7079.7 7092.2 3909.6 3919.6 3959.0 3962.0 3984.3 2935.4 2972.1 2988.6 2998.7 3001.6 504.4 507.2 512.4 513.9 518.4 629.7 639.9 641.3 645.7 666.1 7815.8 7857.3 8025.8 8176.1 8497.5 493.3 601.7 618.7 626.3 647.8 3766.6 3909.5 3940.5 3947.8 3970.6 50778.5 51816.2 51829.2 52343.2 52474.5 44370.6 45267.8 45443.5 45533.0 45705.3 41685.8 41899.0 42309.2 42647.8 42991.8 7611.7 7645.2 7660.5 7837.4 7840.8 28266.9 28274.6 29042.6 29265.6 29472.6 36003.5 38145.0 38343.0 38420.0 38487.8 9523.3 9692.7 9700.3 9707.3 10965.1 10770.5 10791.5 11605.7 11823.6 12191.5 10154.8 10376.5 10377.9 10409.4 10816.4 10748.8 11200.2 11271.4 11640.5 11661.8 10583.4 10752.6 11056.9 11456.8 11460.6 9920.9 9976.5 10032.6 10262.9 10388.2 9341.4 9750.1 9923.2 9925.9 9983.6 15:51:48 INFO - PROCESS | 22891 | -------- Summary: end -------- 15:51:48 INFO - PROCESS | 22891 | 15:51:48 INFO - TEST-INFO | 22891: exit 0 15:51:48 INFO - TEST-OK | dromaeo_css | took 1114567ms 15:51:48 INFO - TEST-START | kraken 15:51:48 INFO - operating with platform_type : linux_ 15:51:48 INFO - Initialising browser for kraken test... 15:51:48 INFO - TEST-INFO | started process 23129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZplRR/profile http://localhost:50348/getInfo.html) 15:51:52 INFO - PROCESS | 23129 | 15:51:52 INFO - PROCESS | 23129 | (process:23196): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:51:53 INFO - PROCESS | 23129 | __metrics Screen width/height:1600/1200 15:51:53 INFO - PROCESS | 23129 | colorDepth:24 15:51:53 INFO - PROCESS | 23129 | Browser inner width/height: 1024/697 15:51:53 INFO - PROCESS | 23129 | __metrics 15:51:54 ERROR - PROCESS | 23129 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:51:55 INFO - TEST-INFO | 23129: exit 0 15:51:55 INFO - Browser initialized. 15:51:55 INFO - Running cycle 1/1 for kraken test... 15:51:55 INFO - TEST-INFO | started process 23247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxZplRR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:57 INFO - PROCESS | 23247 | 15:51:57 INFO - PROCESS | 23247 | (process:23308): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 15:52:07 INFO - PROCESS | 23247 | RSS: Main: 165130240 15:52:07 INFO - PROCESS | 23247 | 15:52:09 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50348/tests/kraken/driver.html?testName=audio-beat-detection) 15:52:10 INFO - PROCESS | 23247 | RSS: Main: 168792064 15:52:10 INFO - PROCESS | 23247 | 15:52:13 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50348/tests/kraken/driver.html?testName=audio-dft) 15:52:14 INFO - PROCESS | 23247 | RSS: Main: 172023808 15:52:14 INFO - PROCESS | 23247 | 15:52:17 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50348/tests/kraken/driver.html?testName=audio-fft) 15:52:17 INFO - PROCESS | 23247 | RSS: Main: 164028416 15:52:17 INFO - PROCESS | 23247 | 15:52:20 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50348/tests/kraken/driver.html?testName=audio-oscillator) 15:52:20 INFO - PROCESS | 23247 | RSS: Main: 164085760 15:52:20 INFO - PROCESS | 23247 | 15:52:22 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50348/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:52:23 INFO - PROCESS | 23247 | RSS: Main: 165715968 15:52:23 INFO - PROCESS | 23247 | 15:52:26 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50348/tests/kraken/driver.html?testName=imaging-darkroom) 15:52:27 INFO - PROCESS | 23247 | RSS: Main: 165482496 15:52:27 INFO - PROCESS | 23247 | 15:52:30 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50348/tests/kraken/driver.html?testName=imaging-desaturate) 15:52:31 INFO - PROCESS | 23247 | RSS: Main: 165208064 15:52:31 INFO - PROCESS | 23247 | 15:52:34 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50348/tests/kraken/driver.html?testName=json-parse-financial) 15:52:35 INFO - PROCESS | 23247 | RSS: Main: 165470208 15:52:35 INFO - PROCESS | 23247 | 15:52:36 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50348/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:52:37 INFO - PROCESS | 23247 | RSS: Main: 166633472 15:52:37 INFO - PROCESS | 23247 | 15:52:39 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:52:39 INFO - PROCESS | 23247 | RSS: Main: 166395904 15:52:39 INFO - PROCESS | 23247 | 15:52:42 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:52:42 INFO - PROCESS | 23247 | RSS: Main: 167006208 15:52:42 INFO - PROCESS | 23247 | 15:52:45 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:52:46 INFO - PROCESS | 23247 | RSS: Main: 166621184 15:52:46 INFO - PROCESS | 23247 | 15:52:49 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:52:49 INFO - PROCESS | 23247 | RSS: Main: 166572032 15:52:49 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | Cycle 1(1): loaded http://localhost:50348/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50348/tests/kraken/driver.html?testName=ai-astar) 15:52:51 INFO - PROCESS | 23247 | RSS: Main: 168423424 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | __start_tp_report 15:52:51 INFO - PROCESS | 23247 | _x_x_mozilla_page_load 15:52:51 INFO - PROCESS | 23247 | _x_x_mozilla_page_load_details 15:52:51 INFO - PROCESS | 23247 | |i|pagename|runs| 15:52:51 INFO - PROCESS | 23247 | |0;ai-astar;115;111;115;116;122;126;114;114;114;113 15:52:51 INFO - PROCESS | 23247 | |1;audio-beat-detection;156;132;158;179;143;160;158;135;209;137 15:52:51 INFO - PROCESS | 23247 | |2;audio-dft;164;164;165;176;162;162;163;162;164;163 15:52:51 INFO - PROCESS | 23247 | |3;audio-fft;73;74;74;184;77;84;73;76;162;78 15:52:51 INFO - PROCESS | 23247 | |4;audio-oscillator;95;94;154;95;102;94;146;94;93;144 15:52:51 INFO - PROCESS | 23247 | |5;imaging-gaussian-blur;118;118;117;118;117;117;119;117;118;118 15:52:51 INFO - PROCESS | 23247 | |6;imaging-darkroom;148;146;147;150;147;148;148;148;147;147 15:52:51 INFO - PROCESS | 23247 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;95 15:52:51 INFO - PROCESS | 23247 | |8;json-parse-financial;65;66;64;67;65;65;68;80;67;62 15:52:51 INFO - PROCESS | 23247 | |9;json-stringify-tinderbox;64;66;66;66;68;65;65;66;65;65 15:52:51 INFO - PROCESS | 23247 | |10;stanford-crypto-aes;87;79;81;79;82;81;82;80;82;86 15:52:51 INFO - PROCESS | 23247 | |11;stanford-crypto-ccm;137;135;142;139;131;132;132;143;169;138 15:52:51 INFO - PROCESS | 23247 | |12;stanford-crypto-pbkdf2;178;184;183;178;182;178;176;177;178;176 15:52:51 INFO - PROCESS | 23247 | |13;stanford-crypto-sha256-iterative;78;75;73;73;74;72;73;73;74;75 15:52:51 INFO - PROCESS | 23247 | __end_tp_report 15:52:51 INFO - PROCESS | 23247 | __start_cc_report 15:52:51 INFO - PROCESS | 23247 | _x_x_mozilla_cycle_collect,896 15:52:51 INFO - PROCESS | 23247 | __end_cc_report 15:52:51 INFO - PROCESS | 23247 | __startTimestamp1461192771937__endTimestamp 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | ------- Summary: start ------- 15:52:51 INFO - PROCESS | 23247 | Number of tests: 14 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#0] ai-astar Cycles:10 Average:116.00 Median:115.00 stddev:4.52 (3.9%) stddev-sans-first:4.78 15:52:51 INFO - PROCESS | 23247 | Values: 115.0 111.0 115.0 116.0 122.0 126.0 114.0 114.0 114.0 113.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#1] audio-beat-detection Cycles:10 Average:156.70 Median:158.00 stddev:23.34 (14.8%) stddev-sans-first:24.76 15:52:51 INFO - PROCESS | 23247 | Values: 156.0 132.0 158.0 179.0 143.0 160.0 158.0 135.0 209.0 137.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:4.17 (2.5%) stddev-sans-first:4.42 15:52:51 INFO - PROCESS | 23247 | Values: 164.0 164.0 165.0 176.0 162.0 162.0 163.0 162.0 164.0 163.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#3] audio-fft Cycles:10 Average:95.50 Median:75.00 stddev:41.30 (55.1%) stddev-sans-first:43.00 15:52:51 INFO - PROCESS | 23247 | Values: 73.0 74.0 74.0 184.0 77.0 84.0 73.0 76.0 162.0 78.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:94.00 stddev:25.71 (27.3%) stddev-sans-first:26.60 15:52:51 INFO - PROCESS | 23247 | Values: 95.0 94.0 154.0 95.0 102.0 94.0 146.0 94.0 93.0 144.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 15:52:51 INFO - PROCESS | 23247 | Values: 118.0 118.0 117.0 118.0 117.0 117.0 119.0 117.0 118.0 118.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 15:52:51 INFO - PROCESS | 23247 | Values: 148.0 146.0 147.0 150.0 147.0 148.0 148.0 148.0 147.0 147.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:52:51 INFO - PROCESS | 23247 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.50 stddev:4.91 (7.4%) stddev-sans-first:5.16 15:52:51 INFO - PROCESS | 23247 | Values: 65.0 66.0 64.0 67.0 65.0 65.0 68.0 80.0 67.0 62.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:0.97 15:52:51 INFO - PROCESS | 23247 | Values: 64.0 66.0 66.0 66.0 68.0 65.0 65.0 66.0 65.0 65.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:2.69 (3.3%) stddev-sans-first:2.12 15:52:51 INFO - PROCESS | 23247 | Values: 87.0 79.0 81.0 79.0 82.0 81.0 82.0 80.0 82.0 86.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:138.50 stddev:11.06 (8.0%) stddev-sans-first:11.69 15:52:51 INFO - PROCESS | 23247 | Values: 137.0 135.0 142.0 139.0 131.0 132.0 132.0 143.0 169.0 138.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:178.00 stddev:2.91 (1.6%) stddev-sans-first:3.06 15:52:51 INFO - PROCESS | 23247 | Values: 178.0 184.0 183.0 178.0 182.0 178.0 176.0 177.0 178.0 176.0 15:52:51 INFO - PROCESS | 23247 | 15:52:51 INFO - PROCESS | 23247 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:74.00 stddev:1.70 (2.3%) stddev-sans-first:1.01 15:52:51 INFO - PROCESS | 23247 | Values: 78.0 75.0 73.0 73.0 74.0 72.0 73.0 73.0 74.0 75.0 15:52:51 INFO - PROCESS | 23247 | -------- Summary: end -------- 15:52:51 INFO - PROCESS | 23247 | 15:52:52 INFO - TEST-INFO | 23247: exit 0 15:52:52 INFO - TEST-OK | kraken | took 64020ms 15:52:52 INFO - SUITE-END | took 1178s 15:52:53 INFO - Completed test suite (00:19:38) 15:52:53 INFO - javascript benchmark 15:52:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.395252115753, 2637.4197676511167, 2641.062422839893, 2673.358393985444, 2680.678318601767, 4629.745364004978, 4649.442066951966, 4658.021815418762, 4705.223638099718, 4724.1260366832075, 4606.170889239936, 4666.580007799297, 4699.318598803172, 4739.218029025206, 4743.739094349809, 4289.914201715972, 4430.889227769299, 4434.600885920267, 4440.333949907507, 4441.378207051015, 567.1421869634677, 4317.892052698692, 4332.891677708067, 4420.4253447052, 4441.75570343631, 4386.802593883251, 4401.185780630562, 4404.986853023823, 4406.339049142623, 4443.755593442359, 4194.790260486963, 4474.865754027384, 4478.193925093482, 4489.326601009842, 4522.706024108423, 4278.42241297424, 4312.61186493214, 4335.674824388184, 4353.695241333108, 4354.738715677069, 4600.539946005406, 4601.493835678073, 4606.792694328763, 4678.625709943196, 4696.741679207642, 5004.324416203803, 5021.623378246646, 5026.5727413169925, 5026.899462010776, 5089.694618322913, 5055.266986287004, 5062.139436295838, 5064.594832413398, 5074.974625126888, 5086.593072554187, 4676.55572720591, 4716.834910778106, 4717.7169369837575, 4747.0505898820375, 4760.523947605281, 5031.270465782441, 5118.10433174193, 5154.149565321755, 5183.870403239949, 5208.557272631793, 4959.628027897922, 4961.2806143109765, 4987.426445958688, 4989.201727723546, 5026.54761071498, 5028.974855125701, 5047.848564543069, 5059.595232381401, 5069.163588008011, 5072.391313042459, 4872.731999740048, 4988.7505624718615, 5024.698518088926, 5030.0191462664425, 5086.796528138907, 4758.1435341638835, 4952.0, 4963.255511673278, 4970.304157417964, 5013.323201367842, 4164.562720914321, 4193.266178418765, 4206.369044643328, 4228.344606585984, 4229.2598795210715, 3627.256412435497, 3711.6081469448955, 3742.3076730804887, 3765.585785563586, 3783.5838057813617, 4301.182775272689, 4334.0, 4344.348347747863, 4370.278903980808, 4373.059792144704, 4984.028114517634, 4986.276989836453, 5001.149804533238, 5011.924821127686, 5029.823956161516, 4347.608715215646, 4348.412964249849, 4353.564643535621, 4362.781860906942, 4395.120975804852, 4463.687541872103, 4472.418585583853, 4501.707389019703, 4505.144022635689, 4554.612857907049, 4711.034237981137, 4759.428868535729, 4760.690555113906, 4782.115308667769, 4790.065937142223, 4984.0, 5006.874828129326, 5012.22310541852, 5016.4231113420965, 5019.824306149268, 4714.292856071616, 4724.881877953078, 4725.031368569366, 4726.291056341576, 4773.949731059161, 4751.501092385388, 4958.033183529177, 4967.552920237052, 5003.799848006039, 5017.02168077236, 5006.848424862229, 5010.924836127534, 5025.648204625787, 5033.370828646419, 5049.747512624427, 4750.382450281441, 4881.8535443936735, 4890.364252647133, 4894.706317620954, 4943.332650092191, 4815.975920120377, 4821.710697358169, 4846.0, 4859.343988561641, 4885.975570122127, 4673.415823022122, 4714.457837348612, 4805.567498925114, 4853.150698627796, 4873.293372461044, 4742.146413645577, 4879.512048795092, 4889.413270407574, 4892.510748925079, 4943.937053533507, 4852.539008794159, 4873.049755297683, 4896.11869863428, 4913.533214344631, 4948.183549714401], "value": 4592.074298573317, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.6371803459551, 587.9353271140175, 603.7313395538985, 609.2932198649568, 627.7991042866284, 34716.305673886505, 36002.63997360026, 36040.81979590105, 36102.0, 36112.81943590281, 36067.819660901725, 37027.44458833113, 37046.8147659262, 37089.072773180684, 37188.628113718856, 7578.696852125909, 7803.765887023398, 7807.609619519001, 7812.531248125102, 7830.530168189898, 7656.770296891103, 7882.842343153134, 7984.720534781284, 7987.760367188965, 7998.0, 7960.203979602052, 8016.0, 8016.919830801705, 8031.9598402007905, 8043.517388956652, 7905.407094467938, 7954.602269886512, 7979.962604861331, 7997.720079797208, 8004.559749213791, 7820.569868657221, 7891.289783919475, 7906.802329941797, 7920.564368959705, 7959.96020019902, 28950.565741513896, 33220.833895830365, 33860.49209261863, 33877.49183762245, 33924.321513569834, 3070.815751054944, 3094.6131733533307, 3221.951670724941, 3258.9348213035605, 3342.1644588852787, 27402.862985685144, 28604.85697571499, 29499.70500294991, 29689.257768555744, 30725.231869203224, 3461.671141241578, 3505.2989402119424, 3589.766665166782, 3699.3896007158787, 3733.0480727414338, 237292.0, 241212.0, 242147.0, 243496.99999999822, 244795.0, 33900.830495847855, 34427.31145377128, 34588.65411345854, 57623.42376576181, 58190.0, 33808.323833523194, 33827.4925876112, 33880.0, 33908.0, 34001.14997125092, 12385.814212786816, 12640.431180596712, 12650.557230496888, 12671.556495522613, 12701.555445559361, 10089.344192627455, 10122.645707400206, 10163.593456261815, 10224.335418197794, 10242.027007434282, 8291.626876790559, 8308.958455207685, 8377.078521362768, 8382.413231073888, 8427.494350338999, 3276.5576647152807, 3301.422251106095, 3319.6016478022793, 3355.1444381682513, 3382.4926261061037, 902.4540153207215, 905.108468158863, 918.3158546882615, 979.3927764785878, 1005.4771518810178, 27601.447971040467, 28156.0, 28920.710792892223, 29188.854055729586, 29305.706942930723, 985.4383001688968, 1009.4902074452501, 1066.6746642274181, 1085.9629054253328, 1132.3828513460185, 15017.324220410108, 15235.771463428058, 15562.688746225012, 15713.450029248921, 15827.841721582867, 350390.0, 472891.0, 481326.0, 484355.0, 485212.0, 46018.53981460143, 46488.0, 46947.76526117416, 47032.294515582296, 47149.0, 1153.9538418463335, 1258.3016425883723, 1260.4517035089766, 1261.369315342329, 1267.4169881854266, 28293.43413131767, 29293.70706292995, 29360.706392935797, 29813.0, 30160.24599385033, 43764.781176093915, 44369.556304437836, 44640.0, 44807.77596111999, 44845.775771122244, 326363.0, 326496.0, 328129.0, 328488.0, 329625.0, 26046.869765651052, 26263.606045908946, 26572.734272657028, 26949.461010780065, 26997.595036074083, 42107.57892421159, 43096.35355469608, 43422.0, 43740.0, 43867.341989869536, 37216.44175337426, 37281.81359093296, 37660.62339376571, 37717.0, 37999.24001520009, 36025.27949441049, 36546.451803222444, 37144.814275928446, 37275.62724372721, 37476.2504749898, 31918.52122218122, 36302.27395452129, 36598.817005915866, 37027.444588330654, 37487.81256093702, 29591.408171836872, 31205.687943121186, 31497.685023150392, 31633.841830790698, 32164.51753223657, 24416.145434909693, 24455.266342009767, 24899.87550062238, 25172.496550069263, 25441.49117017686], "value": 16554.424883021333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.27253310977363, 369.8816378758797, 369.95930447650755, 382.134465435788, 389.89083056744124, 15389.692206155883, 16232.107234102119, 17360.47918562442, 17365.131743412843, 17454.127293635316, 15693.843061569381, 16121.838781612181, 17103.657926841457, 17138.31446742129, 17196.742048869277, 5099.311592934943, 5248.8687782805355, 5259.658122222043, 5273.182656688219, 5288.524032837044, 3681.6134305897895, 5159.329287192679, 5703.116017017369, 5987.6108052976415, 6027.939720602803, 5944.88110237795, 5958.165856780054, 5972.820815375546, 5974.253218347707, 5977.731002104916, 5719.571032172583, 5921.940780592225, 5928.199693041427, 5944.10838374243, 5958.553108516879, 5069.366329208849, 5122.33409656746, 5162.974185129088, 5178.689278643294, 5232.424433312332, 19937.401877943685, 20138.89930550338, 20212.19151233949, 20416.489587760425, 20439.795602043938, 1802.4592622213415, 1929.8236883618445, 1972.7632684078003, 2224.1881713174735, 2455.8580260178924, 12864.29246391448, 13239.470421183141, 13358.732825343439, 13422.798658020138, 13494.730105397935, 1717.3731587970426, 1729.483433212825, 1787.6156626325403, 1802.044916194419, 1805.1154934082206, 64995.35004649987, 65040.349596503904, 65417.0, 65533.67233163851, 65946.3405365945, 8959.328050396247, 9106.81786364269, 9322.161005509537, 9516.666916657883, 9624.807503849885, 12403.813942790865, 12428.0, 12469.251844889335, 12477.5008999639, 12510.061745369134, 6720.630365329856, 6765.8646827063185, 6818.28408017161, 6969.059177011039, 7030.683619237147, 6250.406211409909, 6375.234971803413, 6449.838754031093, 6475.190601174853, 6534.215894092739, 5728.427157284238, 5989.251343582052, 6014.909776353358, 6036.913355595947, 6121.602095863755, 1601.5115389806222, 1602.7115119278408, 1640.811306699721, 1757.9824201758074, 1766.5495298699007, 879.2921698033144, 896.4262871762078, 969.8254314223508, 987.7975015121883, 997.7206382212992, 15415.229238538028, 16530.83469165293, 16686.916565417094, 16912.661746764996, 17166.05586692719, 902.4134312696668, 919.8988111307751, 922.8292765838341, 973.5132433783108, 977.2848650618262, 9567.282453815991, 9578.425294482353, 9607.663731769355, 9703.805923881482, 9814.754631134136, 351360.0, 355168.0, 357148.0, 357467.0, 358382.0, 50358.0, 65573.67213163903, 68469.3153068463, 68708.31291687119, 70375.64812175906, 953.7138858342609, 959.0170075672352, 973.3332667123043, 975.0493269062749, 983.2036050798732, 14885.251442745173, 14945.701085978437, 14951.0, 15012.0, 15223.162726050174, 30033.249168770955, 30760.230994225323, 32488.675113248566, 32811.0, 35549.111272218404, 44625.0, 45550.0, 48818.75590622024, 48871.266930996775, 49113.508864910895, 7257.52826066304, 7570.0, 7809.219078092373, 7819.804504887424, 7857.253560911704, 6612.768553100618, 9399.248060155307, 10115.887252402083, 10872.565097396016, 11189.272697274977, 9939.30424870281, 10309.48452577383, 10464.215183861088, 10528.684139475828, 10714.571417143226, 9877.901220987698, 10038.548265327932, 10060.295779295378, 10233.232507562112, 10359.741006474898, 10597.311174773615, 10621.522031508755, 10655.467226638482, 10730.302530335503, 10747.677569672922, 7812.125329709819, 7817.570033648204, 7989.400794940519, 8039.959800201195, 8046.235607617267, 10065.094141527297, 10122.342047766871, 10182.796344073226, 10343.224258180517, 10441.0], "value": 8219.13484415568, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.9061461005033, 1469.1111877314224, 1602.4791942618644, 1766.6908291049062, 1790.9283628654846, 6674.466042716583, 7346.926530734704, 7424.220456852027, 7706.961465192673, 7745.651445684936, 7511.887321690179, 7527.7741667749915, 7579.0, 7580.583067931276, 7585.1656317805, 2196.461866842618, 2217.090992692996, 2222.7221597300336, 2222.8107962240206, 2224.1992882562263, 2217.2018073493527, 2227.164813195052, 2231.241377931503, 2240.182333448288, 2246.8314876384256, 2240.1487434774763, 2245.158065725353, 2258.4128126687015, 2261.231181398324, 2263.909443622253, 2152.817010554105, 2159.265849611132, 2162.2756376613775, 2165.4911095892453, 2179.2154824263253, 2113.3131732187103, 2117.1637203304763, 2117.7035215069905, 2124.936251912445, 2125.5430082532175, 7655.004849369547, 7670.616469176574, 7720.691172353099, 7721.575313357763, 7726.266004729542, 4438.533953934822, 4582.816687332503, 4584.610308123813, 4587.954120458786, 4598.678092533524, 2865.8137221080565, 2912.3301640622562, 2934.5158048921903, 2968.2876109733725, 3031.1967328657925, 953.8759961204998, 968.6125549780074, 979.6424305128648, 992.4987881120061, 1003.2976916158718, 10456.32033917793, 10491.370517768959, 10536.578536858517, 10541.525631346607, 10555.73610659732, 4167.5207351154395, 4171.3325867860995, 4330.198913201068, 4385.978070109629, 4389.231884420214, 9002.099790020946, 9009.63961441535, 9024.187823095954, 9058.954705226432, 9079.137481939206, 4628.375169352094, 4637.956459796586, 4674.929876051862, 4679.906401872012, 4689.50760170184, 3604.675579197832, 3987.860424885115, 4117.91764164715, 4236.576342365739, 4239.957600423956, 3608.783472991629, 3624.5469316335457, 3700.500432441586, 3728.2357116790995, 3735.34631439497, 1179.9469023893946, 1192.0940085534883, 1195.7010747313172, 1197.9401029948467, 1209.9213551119326, 851.1190917400585, 865.3250464637592, 906.3972458315278, 911.0343036381324, 923.5613083785148, 5444.972775136178, 5453.890922181534, 5473.452654734574, 5504.944950550524, 5632.577556683265, 425.6573458366045, 433.24182680309457, 437.11920480232357, 448.1619371774802, 448.4663250731619, 3830.444585535082, 3861.7296789225047, 3895.162540053902, 3926.9607303926596, 4222.556631553704, 8985.550722463851, 9115.99724030356, 9166.954165229132, 9218.308626853013, 9247.861282080774, 9026.007139214682, 9033.50315732641, 9075.954620226856, 9095.681651142178, 9118.498482583522, 7022.40309573692, 7130.322619351257, 7247.9637601811655, 7298.452616053711, 7326.82770756687, 5707.743151558273, 5724.141378793214, 5749.9712501437225, 5783.219265399285, 5940.910886336622, 8797.340199484936, 8812.735617931472, 8994.370394072352, 9085.954570227106, 9130.543472826199, 8809.471431714117, 9016.684416045407, 9032.683856065007, 9061.54692265371, 9089.77275568116, 6049.455549000611, 6509.218893732593, 6797.456203503609, 6802.761903333359, 6921.861562768616, 7413.369863561657, 7415.962920185365, 7430.517016393917, 7465.3654439371085, 7494.100707914867, 6605.0, 6783.626900520321, 6785.864282714219, 6804.183497980274, 6934.653267336714, 6701.564398314094, 6703.698333575098, 6730.562513436611, 6742.258351581224, 6920.515563910478, 6140.631562106288, 6490.091387205889, 6526.510511711545, 6535.0, 6552.770653027121, 4827.106985207748, 4832.855014349575, 4869.123557759637, 4921.434035086011, 4983.327250821238, 4664.300354946785, 4709.270063140219, 4719.339292499121, 4754.334393185026, 4815.012922350839], "value": 4110.367915751842, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.3031457961269, 306.55090292721167, 308.4078569147237, 313.94505961456724, 315.65278193986603, 5206.531412172903, 5278.287431196787, 5317.78728850846, 5323.653962492435, 5338.439463856293, 5193.16909294513, 5214.191800270964, 5223.112070947938, 5227.634065615409, 5250.789968401269, 3826.827792749319, 3894.1432884765304, 4059.7564146151176, 4072.470578824749, 4090.754554726726, 4034.838606455753, 4054.574269701683, 4081.979590102045, 4084.89787755307, 4098.118904435546, 4045.7370270932443, 4064.9593504065024, 4066.674666026711, 4067.2475592015426, 4072.6741860651223, 3798.373268410709, 3987.521497420328, 4013.3979903014488, 4031.2340655275552, 4044.170944956292, 3477.2871561329866, 3486.4770284457536, 3499.7900125992523, 3501.9474707879403, 3502.369573476773, 4985.900281994376, 4997.175466048097, 4999.075171093359, 5005.424376196747, 5040.36995375578, 4354.629856462205, 4578.656600754924, 4604.88487787808, 4622.86131416058, 4638.628909687217, 5474.479924407175, 5507.0, 5507.366652834935, 5507.669539827583, 5516.558675306006, 3813.5127300352883, 3816.580176180618, 3831.7700937943814, 3833.195029043904, 3841.078141246081, 5540.833774986797, 5741.167530708066, 5783.2192653992015, 5798.739056742456, 5833.912491312633, 3795.69634429245, 3834.539855217336, 3851.0, 3859.054531639766, 3874.341361968472, 3877.651011408987, 3885.572587015426, 3901.7073719471155, 3921.9019524512114, 3933.6459718624888, 4541.863744087683, 4558.430196225472, 4558.49856515783, 4562.703424277412, 4575.084983003413, 5128.589712822965, 5496.0931446310915, 5497.697626630574, 5506.64206826563, 5542.11326187808, 1360.6666366740212, 1377.1736957825224, 1390.64538542673, 1393.470481217131, 1393.540131756518, 853.1638993786124, 888.684516996457, 901.1709227510574, 923.7690577355661, 926.6200857648332, 4741.881452963704, 4758.666893317504, 4771.188897887368, 4808.422989241313, 4818.0, 5617.887642247132, 5634.6337488063145, 5647.040003199466, 5655.822508334321, 5673.149027645886, 4634.513376095461, 4723.5984941279685, 4742.881427964259, 4753.786079626425, 4791.568758811654, 2996.8801247950273, 3029.33354661974, 3038.3619439917425, 3042.756579473637, 3052.8168309901475, 5295.311609490742, 5340.91988620171, 5346.97326513365, 5362.222477740784, 5363.275957745733, 5666.178404131378, 5802.4197580241635, 5806.767729290781, 5815.3021637402935, 5818.301803783488, 5269.815556455505, 5304.098303288451, 5319.654222475527, 5321.201819727072, 5361.919571206435, 5474.972625136849, 5609.32688077417, 5651.321841379061, 5652.717364131695, 5662.490375866192, 5523.502884740393, 5540.0, 5554.888902222014, 5561.749721262634, 5574.442555744393, 5314.202869569595, 5354.839354819362, 5356.250124982584, 5363.115086010765, 5368.785248590013, 3711.9072023199637, 3862.806859657062, 3872.3417019106255, 3873.9418908716957, 3882.902927426837, 5602.607817452739, 5607.411221821813, 5612.943870561242, 5633.464820842013, 5642.689652069175, 5415.566754659697, 5418.972905135449, 5428.592855535771, 5459.181122831607, 5465.480779325958, 5333.573314134937, 5399.379071406838, 5434.483724046209, 5441.347038355423, 5465.890682186414, 5297.8145764898045, 5338.065838478328, 5349.866253343697, 5352.652077614943, 5366.7316634167355, 5285.735713214247, 5438.443931352258, 5630.774769009357, 5630.88738225225, 5667.3482549507335, 5443.918341224964, 5458.208559758892, 5479.479449452296, 5487.945120548743, 5491.862703432446], "value": 4094.141098591866, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.0270108342904, 725.87297222986, 731.7475470962522, 754.7433872483356, 794.9046114466265, 12176.208546444475, 12252.81620775689, 12346.62960111197, 12378.133530652858, 12425.937870310647, 11414.258073225235, 11492.942535287333, 13262.336883155851, 13940.860591394083, 13995.0, 806.8466991271654, 812.7886749445157, 813.9511629302232, 814.7881550796776, 815.7185770909027, 645.286957911508, 787.0830482487895, 808.3694718119876, 808.7654580171743, 809.5102463009882, 850.9106543812903, 852.3607294529103, 852.7782776478099, 853.4537895746727, 857.2284943550792, 830.6303694855792, 837.7528629054443, 844.3118858130604, 845.4504572028169, 847.4195176304221, 622.7135517661835, 623.4513628007325, 623.847157446424, 623.8689875126229, 625.0436831647542, 13327.600171994856, 13391.598252052356, 13392.129511581814, 13396.598102056954, 13412.79880801779, 12717.745645087047, 12753.936230318881, 12767.680807979781, 12906.161099528594, 13059.673508162277, 9834.114929656294, 9975.950120249425, 10083.19334453242, 10090.243231757648, 10398.324108932895, 3260.96739032609, 3298.818564978925, 3335.6164041135094, 3337.93324133516, 3366.4950257461333, 47335.52664473346, 47461.288080678816, 47623.047539049025, 47729.28406073877, 48087.278690819665, 9954.800903981879, 10072.848907266396, 10257.33327333721, 10396.584136634601, 10583.153347732163, 17490.300387984593, 17499.0, 17570.0, 17601.0, 17635.118244087746, 6939.340762627542, 7028.894566581505, 7060.223375428699, 7079.681414336367, 7092.219855815959, 3909.550401703784, 3919.6080391960577, 3958.960410395917, 3961.960380396217, 3984.2828290907914, 2935.3688956874375, 2972.1380799568315, 2988.5965394671875, 2998.7151220634005, 3001.564773107887, 504.4400715206118, 507.1631807517552, 512.3518748782793, 513.850983214871, 518.409013724347, 629.6662768732579, 639.9232092148878, 641.3394203969918, 645.7417033186763, 666.1107421592131, 7815.823954643429, 7857.253560911704, 8025.799355016171, 8176.1006289308125, 8497.490150590984, 493.2921257994789, 601.6600620649319, 618.6552964265384, 626.3392121311969, 647.8412788866664, 3766.6233376662112, 3909.4722212501515, 3940.5074365704286, 3947.782871942071, 3970.6029397060065, 50778.49221507811, 51816.22275665868, 51829.222561661605, 52343.21485177725, 52474.47525524772, 44370.55629443729, 45267.77366113148, 45443.54556454459, 45533.0, 45705.31442028372, 41685.79157104195, 41899.0, 42309.15381692349, 42647.786761065996, 42991.78504107522, 7611.695532178651, 7645.1972542882195, 7660.502067365604, 7837.37301015906, 7840.84318313642, 28266.85866570654, 28274.575881361383, 29042.564361534172, 29265.561016585194, 29472.55791163177, 36003.45994810027, 38145.0, 38343.0, 38420.0, 38487.807560962014, 9523.333366664267, 9692.660756873758, 9700.320977531504, 9707.271954603575, 10965.067969222386, 10770.461476926279, 10791.460426978776, 11605.6518304451, 11823.645290641294, 12191.451384687887, 10154.847677284699, 10376.533056012346, 10377.858435572232, 10409.42748148859, 10816.4050977197, 10748.83876741865, 11200.21598488098, 11271.380074096003, 11640.476178572155, 11661.76676466449, 10583.417912014605, 10752.623658171926, 11056.889431105908, 11456.82814757796, 11460.598879039193, 9920.851187232052, 9976.501174941079, 10032.598696052077, 10262.948685256526, 10388.168946484111, 9341.439513629204, 9750.122488976027, 9923.236092434172, 9925.851112233178, 9983.550740216853], "value": 5999.003471652606, "unit": "score"}], "name": "dromaeo_css", "value": 6309.371240337526, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 115.0, 116.0, 122.0, 126.0, 114.0, 114.0, 114.0, 113.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 132.0, 158.0, 179.0, 143.0, 160.0, 158.0, 135.0, 209.0, 137.0], "value": 156.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 176.0, 162.0, 162.0, 163.0, 162.0, 164.0, 163.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 184.0, 77.0, 84.0, 73.0, 76.0, 162.0, 78.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 154.0, 95.0, 102.0, 94.0, 146.0, 94.0, 93.0, 144.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 119.0, 117.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 147.0, 150.0, 147.0, 148.0, 148.0, 148.0, 147.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 64.0, 67.0, 65.0, 65.0, 68.0, 80.0, 67.0, 62.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 66.0, 68.0, 65.0, 65.0, 66.0, 65.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 81.0, 79.0, 82.0, 81.0, 82.0, 80.0, 82.0, 86.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 142.0, 139.0, 131.0, 132.0, 132.0, 143.0, 169.0, 138.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 184.0, 183.0, 178.0, 182.0, 178.0, 176.0, 177.0, 178.0, 176.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 73.0, 73.0, 74.0, 72.0, 73.0, 73.0, 74.0, 75.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1611.4, "alertThreshold": 2.0}]} 15:52:53 INFO - Return code: 0 15:52:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:52:53 ERROR - # TBPL SUCCESS # 15:52:53 INFO - Running post-action listener: _resource_record_post_action 15:52:53 INFO - Running post-run listener: _resource_record_post_run 15:52:54 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 517816320; Read time: 0; Write time: 554540 15:52:54 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 234143744; Read time: 0; Write time: 367056 15:52:54 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 283500544; Read time: 0; Write time: 187460 15:52:54 INFO - Running post-run listener: _upload_blobber_files 15:52:54 INFO - Blob upload gear active. 15:52:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:52:54 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 15:52:54 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'] 15:52:54 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 15:52:54 INFO - (blobuploader) - INFO - Open directory for files ... 15:52:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:52:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:52:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:55 INFO - (blobuploader) - INFO - Done attempting. 15:52:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:52:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:52:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:52:56 INFO - (blobuploader) - INFO - Done attempting. 15:52:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:52:56 INFO - Return code: 0 15:52:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:52:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:52:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60c24e5be99eb851107efb7e6fbe152ab41d1886424219b889487d5ac5fabfeff5e7730dd57c1aa5ec7dd3d3db954b720a5a4cdea10d140018df2a27fc8f0ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39d4b7623935d6c6fab985d066928a4983041c4042d9c371305cbabdc0d0f6240edf378b916a33d1def04a0eeacf5fb1c397635db4711dd0b0aa1fdb0ff705fa"} 15:52:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:52:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:52:56 INFO - Contents: 15:52:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60c24e5be99eb851107efb7e6fbe152ab41d1886424219b889487d5ac5fabfeff5e7730dd57c1aa5ec7dd3d3db954b720a5a4cdea10d140018df2a27fc8f0ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39d4b7623935d6c6fab985d066928a4983041c4042d9c371305cbabdc0d0f6240edf378b916a33d1def04a0eeacf5fb1c397635db4711dd0b0aa1fdb0ff705fa"} 15:52:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:52:56 INFO - Copying logs to upload dir... 15:52:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:52:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.724914 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-04-20 15:52:56.374146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 15:52:56.377513) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60c24e5be99eb851107efb7e6fbe152ab41d1886424219b889487d5ac5fabfeff5e7730dd57c1aa5ec7dd3d3db954b720a5a4cdea10d140018df2a27fc8f0ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39d4b7623935d6c6fab985d066928a4983041c4042d9c371305cbabdc0d0f6240edf378b916a33d1def04a0eeacf5fb1c397635db4711dd0b0aa1fdb0ff705fa"} build_url:https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/LK_qrQCoSk2ArQA5DyvTaw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/60c24e5be99eb851107efb7e6fbe152ab41d1886424219b889487d5ac5fabfeff5e7730dd57c1aa5ec7dd3d3db954b720a5a4cdea10d140018df2a27fc8f0ee3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39d4b7623935d6c6fab985d066928a4983041c4042d9c371305cbabdc0d0f6240edf378b916a33d1def04a0eeacf5fb1c397635db4711dd0b0aa1fdb0ff705fa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 15:52:56.401692) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:52:56.402011) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1461191071.362032-1890014145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 15:52:56.484036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 15:52:56.484372) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 15:52:56.484683) ========= ========= Total master_lag: 0.19 =========