builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1461021135.62 results: success (0) buildid: 20160418151421 builduid: c837f8bcbe4e4b449ba5f5ddb31d122d revision: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.616477) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.617090) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.617392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.638831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.639147) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-18 16:12:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-04-18 16:12:16 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146532 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.800517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.800820) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014513 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.851855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:15.852182) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 67ac40fb8f680ea5e03805552187ba1b5e8392a1 --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 67ac40fb8f680ea5e03805552187ba1b5e8392a1 --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=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-18 16:12:16,128 truncating revision to first 12 chars 2016-04-18 16:12:16,128 Setting DEBUG logging. 2016-04-18 16:12:16,128 attempt 1/10 2016-04-18 16:12:16,128 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/67ac40fb8f68?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 16:12:16,406 unpacking tar archive at: mozilla-central-67ac40fb8f68/testing/mozharness/ program finished with exit code 0 elapsedTime=0.616964 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:16.482225) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:16.482530) ========= script_repo_revision: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:16.482985) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:16.483252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 16:12:16.493344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-18 16:12:16.493674) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:12:16 INFO - MultiFileLogger online at 20160418 16:12:16 in /builds/slave/test 16:12:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:12:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:16 INFO - {'append_to_log': False, 16:12:16 INFO - 'base_work_dir': '/builds/slave/test', 16:12:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:12:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:16 INFO - 'branch': 'Firefox-Non-PGO', 16:12:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:16 INFO - 'config_files': ('talos/linux_config.py',), 16:12:16 INFO - 'default_actions': ('clobber', 16:12:16 INFO - 'read-buildbot-config', 16:12:16 INFO - 'download-and-extract', 16:12:16 INFO - 'populate-webroot', 16:12:16 INFO - 'create-virtualenv', 16:12:16 INFO - 'install', 16:12:16 INFO - 'run-tests'), 16:12:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:16 INFO - 'download_minidump_stackwalk': True, 16:12:16 INFO - 'download_symbols': 'ondemand', 16:12:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:16 INFO - 'installer_path': 'installer.exe', 16:12:16 INFO - 'log_level': 'info', 16:12:16 INFO - 'log_name': 'talos', 16:12:16 INFO - 'log_to_console': True, 16:12:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:12:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:12:16 INFO - 'opt_config_files': (), 16:12:16 INFO - 'pip_index': False, 16:12:16 INFO - 'sps_profile': False, 16:12:16 INFO - 'sps_profile_interval': 0, 16:12:16 INFO - 'suite': 'dromaeojs', 16:12:16 INFO - 'system_bits': '32', 16:12:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:16 INFO - 'title': 'talos-linux64-ix-033', 16:12:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:16 INFO - 'use_talos_json': True, 16:12:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:16 INFO - 'work_dir': 'build'} 16:12:16 INFO - ##### 16:12:16 INFO - ##### Running clobber step. 16:12:16 INFO - ##### 16:12:16 INFO - Running pre-action listener: _resource_record_pre_action 16:12:16 INFO - Running main action method: clobber 16:12:16 INFO - rmtree: /builds/slave/test/build 16:12:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:17 INFO - Running post-action listener: _resource_record_post_action 16:12:17 INFO - ##### 16:12:17 INFO - ##### Running read-buildbot-config step. 16:12:17 INFO - ##### 16:12:17 INFO - Running pre-action listener: _resource_record_pre_action 16:12:17 INFO - Running main action method: read_buildbot_config 16:12:17 INFO - Using buildbot properties: 16:12:17 INFO - { 16:12:17 INFO - "project": "", 16:12:17 INFO - "product": "firefox", 16:12:17 INFO - "script_repo_revision": "production", 16:12:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:12:17 INFO - "repository": "", 16:12:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:12:17 INFO - "buildid": "20160418151421", 16:12:17 INFO - "pgo_build": "False", 16:12:17 INFO - "basedir": "/builds/slave/test", 16:12:17 INFO - "buildnumber": 476, 16:12:17 INFO - "slavename": "talos-linux64-ix-033", 16:12:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:12:17 INFO - "platform": "ubuntu64_hw", 16:12:17 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:12:17 INFO - "branch": "mozilla-central", 16:12:17 INFO - "repo_path": "mozilla-central", 16:12:17 INFO - "revision": "67ac40fb8f680ea5e03805552187ba1b5e8392a1", 16:12:17 INFO - "stage_platform": "linux64", 16:12:17 INFO - "builduid": "c837f8bcbe4e4b449ba5f5ddb31d122d", 16:12:17 INFO - "slavebuilddir": "test" 16:12:17 INFO - } 16:12:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 16:12:17 INFO - Running post-action listener: _resource_record_post_action 16:12:17 INFO - ##### 16:12:17 INFO - ##### Running download-and-extract step. 16:12:17 INFO - ##### 16:12:17 INFO - Running pre-action listener: _resource_record_pre_action 16:12:17 INFO - Running main action method: download_and_extract 16:12:17 INFO - mkdir: /builds/slave/test/build/tests 16:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:17 INFO - https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:12:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 16:12:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 16:12:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:23 INFO - Downloaded 1361 bytes. 16:12:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 16:12:23 INFO - Using the following test package requirements: 16:12:23 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:23 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:12:23 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:12:23 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'jsshell-linux-x86_64.zip'], 16:12:23 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:12:23 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 16:12:23 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:12:23 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:12:23 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:12:23 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 16:12:23 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:12:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:23 INFO - https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 16:12:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 16:12:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:28 INFO - Downloaded 22209820 bytes. 16:12:28 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'] 16:12:28 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 16:12:29 INFO - Return code: 0 16:12:29 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 16:12: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')]} 16:12:29 INFO - https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:12:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 16:12:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 16:12:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:30 INFO - Downloaded 22209820 bytes. 16:12:30 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'] 16:12:30 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 16:12:32 INFO - Return code: 0 16:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:32 INFO - https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:12:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 16:12:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:34 INFO - Downloaded 10845952 bytes. 16:12:34 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'] 16:12:34 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 16:12:34 INFO - Return code: 0 16:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:34 INFO - https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:12:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 16:12:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 16:12:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VooVRGqdTsm7Tw_FNquKKw/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 16:12:35 INFO - Downloaded 54451002 bytes. 16:12:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 16:12:35 INFO - mkdir: /builds/slave/test/properties 16:12:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:35 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:35 INFO - Contents: 16:12:35 INFO - build_url:https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 16:12:36 INFO - Running post-action listener: _resource_record_post_action 16:12:36 INFO - Running post-action listener: set_extra_try_arguments 16:12:36 INFO - ##### 16:12:36 INFO - ##### Running populate-webroot step. 16:12:36 INFO - ##### 16:12:36 INFO - Running pre-action listener: _resource_record_pre_action 16:12:36 INFO - Running main action method: populate_webroot 16:12:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:36 INFO - '--tppagecycles', 16:12:36 INFO - '7'], 16:12:36 INFO - 'tests': ['tsvgm']}}, 16:12:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['damp', 'tps']}, 16:12:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'tests': ['damp', 'tps']}, 16:12:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['dromaeo_dom']}, 16:12:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['a11yr', 16:12:36 INFO - 'ts_paint', 16:12:36 INFO - 'tpaint', 16:12:36 INFO - 'sessionrestore', 16:12:36 INFO - 'sessionrestore_no_auto_restore', 16:12:36 INFO - 'tabpaint']}, 16:12:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['tsvgx', 16:12:36 INFO - 'tsvgr_opacity', 16:12:36 INFO - 'tart', 16:12:36 INFO - 'tscrollx', 16:12:36 INFO - 'cart']}, 16:12:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:36 INFO - 'tsvgr_opacity', 16:12:36 INFO - 'tart', 16:12:36 INFO - 'tscrollx', 16:12:36 INFO - 'cart']}, 16:12:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'talos_options': ['--disable-e10s'], 16:12:36 INFO - 'tests': ['tp5o']}, 16:12:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'tests': ['tp5o']}, 16:12:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'talos_options': ['--disable-e10s', 16:12:36 INFO - '--xperf_path', 16:12:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:36 INFO - 'tests': ['tp5n']}, 16:12:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:36 INFO - 'talos_options': ['--xperf_path', 16:12:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:36 INFO - 'tests': ['tp5n']}}, 16:12:36 INFO - 'talos.zip': {'path': '', 16:12:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:36 INFO - Running post-action listener: _resource_record_post_action 16:12:36 INFO - ##### 16:12:36 INFO - ##### Running create-virtualenv step. 16:12:36 INFO - ##### 16:12:36 INFO - Running pre-action listener: _resource_record_pre_action 16:12:36 INFO - Running main action method: create_virtualenv 16:12:36 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:12:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:36 INFO - Using real prefix '/usr' 16:12:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:36 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:38 INFO - Installing pip.................done. 16:12:38 INFO - Return code: 0 16:12:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12: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', 'pip>=1.5'] in /builds/slave/test/build 16:12: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 pip>=1.5 16:12:38 INFO - Using env: {'DISPLAY': ':0', 16:12:38 INFO - 'HOME': '/home/cltbld', 16:12:38 INFO - 'LANG': 'en_US.UTF-8', 16:12:38 INFO - 'LANGUAGE': 'en_US:en', 16:12:38 INFO - 'LOGNAME': 'cltbld', 16:12:38 INFO - 'MAIL': '/var/mail/cltbld', 16:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:38 INFO - 'MOZ_NO_REMOTE': '1', 16:12:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:38 INFO - 'NO_EM_RESTART': '1', 16:12:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:38 INFO - 'PWD': '/builds/slave/test', 16:12:38 INFO - 'SHELL': '/bin/bash', 16:12:38 INFO - 'SHLVL': '1', 16:12:38 INFO - 'TERM': 'linux', 16:12:38 INFO - 'TMOUT': '86400', 16:12:38 INFO - 'USER': 'cltbld', 16:12:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:38 INFO - '_': '/tools/buildbot/bin/python'} 16:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:12:39 INFO - Cleaning up... 16:12:39 INFO - Return code: 0 16:12:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:39 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:39 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 16:12:39 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 16:12:39 INFO - Using env: {'DISPLAY': ':0', 16:12:39 INFO - 'HOME': '/home/cltbld', 16:12:39 INFO - 'LANG': 'en_US.UTF-8', 16:12:39 INFO - 'LANGUAGE': 'en_US:en', 16:12:39 INFO - 'LOGNAME': 'cltbld', 16:12:39 INFO - 'MAIL': '/var/mail/cltbld', 16:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:39 INFO - 'MOZ_NO_REMOTE': '1', 16:12:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:39 INFO - 'NO_EM_RESTART': '1', 16:12:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:39 INFO - 'PWD': '/builds/slave/test', 16:12:39 INFO - 'SHELL': '/bin/bash', 16:12:39 INFO - 'SHLVL': '1', 16:12:39 INFO - 'TERM': 'linux', 16:12:39 INFO - 'TMOUT': '86400', 16:12:39 INFO - 'USER': 'cltbld', 16:12:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:39 INFO - '_': '/tools/buildbot/bin/python'} 16:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:39 INFO - Downloading/unpacking psutil>=0.7.1 16:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:41 INFO - Installing collected packages: psutil 16:12:41 INFO - Running setup.py install for psutil 16:12:41 INFO - building 'psutil._psutil_linux' extension 16:12:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:12:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:12:41 INFO - building 'psutil._psutil_posix' extension 16:12:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:12:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:12:41 INFO - Successfully installed psutil 16:12:41 INFO - Cleaning up... 16:12:41 INFO - Return code: 0 16:12:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:41 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:41 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 16:12:41 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 16:12:41 INFO - Using env: {'DISPLAY': ':0', 16:12:41 INFO - 'HOME': '/home/cltbld', 16:12:41 INFO - 'LANG': 'en_US.UTF-8', 16:12:41 INFO - 'LANGUAGE': 'en_US:en', 16:12:41 INFO - 'LOGNAME': 'cltbld', 16:12:41 INFO - 'MAIL': '/var/mail/cltbld', 16:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:41 INFO - 'MOZ_NO_REMOTE': '1', 16:12:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:41 INFO - 'NO_EM_RESTART': '1', 16:12:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:41 INFO - 'PWD': '/builds/slave/test', 16:12:41 INFO - 'SHELL': '/bin/bash', 16:12:41 INFO - 'SHLVL': '1', 16:12:41 INFO - 'TERM': 'linux', 16:12:41 INFO - 'TMOUT': '86400', 16:12:41 INFO - 'USER': 'cltbld', 16:12:41 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:41 INFO - '_': '/tools/buildbot/bin/python'} 16:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:12:44 INFO - Installing collected packages: mozsystemmonitor 16:12:44 INFO - Running setup.py install for mozsystemmonitor 16:12:44 INFO - Successfully installed mozsystemmonitor 16:12:44 INFO - Cleaning up... 16:12:44 INFO - Return code: 0 16:12:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:44 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:12:44 INFO - Using env: {'DISPLAY': ':0', 16:12:44 INFO - 'HOME': '/home/cltbld', 16:12:44 INFO - 'LANG': 'en_US.UTF-8', 16:12:44 INFO - 'LANGUAGE': 'en_US:en', 16:12:44 INFO - 'LOGNAME': 'cltbld', 16:12:44 INFO - 'MAIL': '/var/mail/cltbld', 16:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:44 INFO - 'MOZ_NO_REMOTE': '1', 16:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:44 INFO - 'NO_EM_RESTART': '1', 16:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:44 INFO - 'PWD': '/builds/slave/test', 16:12:44 INFO - 'SHELL': '/bin/bash', 16:12:44 INFO - 'SHLVL': '1', 16:12:44 INFO - 'TERM': 'linux', 16:12:44 INFO - 'TMOUT': '86400', 16:12:44 INFO - 'USER': 'cltbld', 16:12:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:44 INFO - '_': '/tools/buildbot/bin/python'} 16:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:12:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:12:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:46 INFO - Downloading docopt-0.6.1.tar.gz 16:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:12:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:12:47 INFO - Running setup.py install for blobuploader 16:12:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:12:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:12:47 INFO - Running setup.py install for requests 16:12:47 INFO - Running setup.py install for docopt 16:12:47 INFO - Successfully installed blobuploader requests docopt 16:12:47 INFO - Cleaning up... 16:12:47 INFO - Return code: 0 16:12:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:47 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:47 INFO - Using env: {'DISPLAY': ':0', 16:12:47 INFO - 'HOME': '/home/cltbld', 16:12:47 INFO - 'LANG': 'en_US.UTF-8', 16:12:47 INFO - 'LANGUAGE': 'en_US:en', 16:12:47 INFO - 'LOGNAME': 'cltbld', 16:12:47 INFO - 'MAIL': '/var/mail/cltbld', 16:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:47 INFO - 'MOZ_NO_REMOTE': '1', 16:12:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:47 INFO - 'NO_EM_RESTART': '1', 16:12:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:47 INFO - 'PWD': '/builds/slave/test', 16:12:47 INFO - 'SHELL': '/bin/bash', 16:12:47 INFO - 'SHLVL': '1', 16:12:47 INFO - 'TERM': 'linux', 16:12:47 INFO - 'TMOUT': '86400', 16:12:47 INFO - 'USER': 'cltbld', 16:12:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:47 INFO - '_': '/tools/buildbot/bin/python'} 16:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:47 INFO - Running setup.py (path:/tmp/pip-AduNR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:47 INFO - Running setup.py (path:/tmp/pip-d50Zxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:47 INFO - Running setup.py (path:/tmp/pip-u_CQYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:48 INFO - Running setup.py (path:/tmp/pip-rWQwe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:48 INFO - Running setup.py (path:/tmp/pip-zGlBVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:48 INFO - Running setup.py (path:/tmp/pip-fNbs93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:48 INFO - Running setup.py (path:/tmp/pip-fDUlXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:48 INFO - Running setup.py (path:/tmp/pip-Lqc7Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:48 INFO - Running setup.py (path:/tmp/pip-WZylzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:48 INFO - Running setup.py (path:/tmp/pip-LisT74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:48 INFO - Running setup.py (path:/tmp/pip-yvv4MC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:48 INFO - Running setup.py (path:/tmp/pip-B2WZvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:48 INFO - Running setup.py (path:/tmp/pip-qMZ5FG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:48 INFO - Running setup.py (path:/tmp/pip-uZ4KCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:48 INFO - Running setup.py (path:/tmp/pip-FDXS4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:48 INFO - Running setup.py (path:/tmp/pip-FabLhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:48 INFO - Running setup.py (path:/tmp/pip-qetM9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:12:49 INFO - Running setup.py install for manifestparser 16:12:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Running setup.py install for mozcrash 16:12:49 INFO - Running setup.py install for mozdebug 16:12:49 INFO - Running setup.py install for mozdevice 16:12:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Running setup.py install for mozfile 16:12:49 INFO - Running setup.py install for mozhttpd 16:12:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Running setup.py install for mozinfo 16:12:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Running setup.py install for mozInstall 16:12:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:12:49 INFO - Running setup.py install for mozleak 16:12:49 INFO - Running setup.py install for mozlog 16:12:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Running setup.py install for moznetwork 16:12:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Running setup.py install for mozprocess 16:12:50 INFO - Running setup.py install for mozprofile 16:12:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Running setup.py install for mozrunner 16:12:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Running setup.py install for mozscreenshot 16:12:50 INFO - Running setup.py install for moztest 16:12:50 INFO - Running setup.py install for mozversion 16:12:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:12:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:12:50 INFO - Cleaning up... 16:12:50 INFO - Return code: 0 16:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:50 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:12:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:50 INFO - Using env: {'DISPLAY': ':0', 16:12:50 INFO - 'HOME': '/home/cltbld', 16:12:50 INFO - 'LANG': 'en_US.UTF-8', 16:12:50 INFO - 'LANGUAGE': 'en_US:en', 16:12:50 INFO - 'LOGNAME': 'cltbld', 16:12:50 INFO - 'MAIL': '/var/mail/cltbld', 16:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:50 INFO - 'MOZ_NO_REMOTE': '1', 16:12:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:50 INFO - 'NO_EM_RESTART': '1', 16:12:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:50 INFO - 'PWD': '/builds/slave/test', 16:12:50 INFO - 'SHELL': '/bin/bash', 16:12:50 INFO - 'SHLVL': '1', 16:12:50 INFO - 'TERM': 'linux', 16:12:50 INFO - 'TMOUT': '86400', 16:12:50 INFO - 'USER': 'cltbld', 16:12:50 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:50 INFO - '_': '/tools/buildbot/bin/python'} 16:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:12:51 INFO - Running setup.py (path:/tmp/pip-vou8wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:12:51 INFO - Running setup.py (path:/tmp/pip-QhFV9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:12:51 INFO - Running setup.py (path:/tmp/pip-wBTc6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:12:51 INFO - Running setup.py (path:/tmp/pip-FiXNS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:12:51 INFO - Running setup.py (path:/tmp/pip-xwYNXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:12:51 INFO - Running setup.py (path:/tmp/pip-DNoA7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:12:51 INFO - Running setup.py (path:/tmp/pip-qrVNZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:12:51 INFO - Running setup.py (path:/tmp/pip-19uudB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:12:51 INFO - Running setup.py (path:/tmp/pip-qzGg0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:12:51 INFO - Running setup.py (path:/tmp/pip-VIajlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:12:51 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)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:12:51 INFO - Running setup.py (path:/tmp/pip-VGS84i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:12:51 INFO - Running setup.py (path:/tmp/pip-9tBU8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:12:51 INFO - Running setup.py (path:/tmp/pip-DgviN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:12:52 INFO - Running setup.py (path:/tmp/pip-XXPbFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:52 INFO - Running setup.py (path:/tmp/pip-l1q3_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:12:52 INFO - Running setup.py (path:/tmp/pip-ReXMAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:12:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:12:52 INFO - Running setup.py (path:/tmp/pip-GWF567-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:12:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:54 INFO - Downloading blessings-1.6.tar.gz 16:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:12:54 INFO - Installing collected packages: blessings 16:12:54 INFO - Running setup.py install for blessings 16:12:54 INFO - Successfully installed blessings 16:12:54 INFO - Cleaning up... 16:12:54 INFO - Return code: 0 16:12:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:12:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:12:54 INFO - Reading from file tmpfile_stdout 16:12:54 INFO - Current package versions: 16:12:54 INFO - argparse == 1.2.1 16:12:54 INFO - blessings == 1.6 16:12:54 INFO - blobuploader == 1.2.4 16:12:54 INFO - docopt == 0.6.1 16:12:54 INFO - manifestparser == 1.1 16:12:54 INFO - mozInstall == 1.12 16:12:54 INFO - mozcrash == 0.17 16:12:54 INFO - mozdebug == 0.1 16:12:54 INFO - mozdevice == 0.48 16:12:54 INFO - mozfile == 1.2 16:12:54 INFO - mozhttpd == 0.7 16:12:54 INFO - mozinfo == 0.9 16:12:54 INFO - mozleak == 0.1 16:12:54 INFO - mozlog == 3.1 16:12:54 INFO - moznetwork == 0.27 16:12:54 INFO - mozprocess == 0.22 16:12:54 INFO - mozprofile == 0.28 16:12:54 INFO - mozrunner == 6.11 16:12:54 INFO - mozscreenshot == 0.1 16:12:54 INFO - mozsystemmonitor == 0.0 16:12:54 INFO - moztest == 0.7 16:12:54 INFO - mozversion == 1.4 16:12:54 INFO - psutil == 3.1.1 16:12:54 INFO - requests == 1.2.3 16:12:54 INFO - wsgiref == 0.1.2 16:12:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:54 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:12:54 INFO - Using env: {'DISPLAY': ':0', 16:12:54 INFO - 'HOME': '/home/cltbld', 16:12:54 INFO - 'LANG': 'en_US.UTF-8', 16:12:54 INFO - 'LANGUAGE': 'en_US:en', 16:12:54 INFO - 'LOGNAME': 'cltbld', 16:12:54 INFO - 'MAIL': '/var/mail/cltbld', 16:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:54 INFO - 'MOZ_NO_REMOTE': '1', 16:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:54 INFO - 'NO_EM_RESTART': '1', 16:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:54 INFO - 'PWD': '/builds/slave/test', 16:12:54 INFO - 'SHELL': '/bin/bash', 16:12:54 INFO - 'SHLVL': '1', 16:12:54 INFO - 'TERM': 'linux', 16:12:54 INFO - 'TMOUT': '86400', 16:12:54 INFO - 'USER': 'cltbld', 16:12:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:54 INFO - '_': '/tools/buildbot/bin/python'} 16:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:12:54 INFO - Cleaning up... 16:12:54 INFO - Return code: 0 16:12:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12: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', 'jsonschema'] in /builds/slave/test/build 16:12: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 jsonschema 16:12:54 INFO - Using env: {'DISPLAY': ':0', 16:12:54 INFO - 'HOME': '/home/cltbld', 16:12:54 INFO - 'LANG': 'en_US.UTF-8', 16:12:54 INFO - 'LANGUAGE': 'en_US:en', 16:12:54 INFO - 'LOGNAME': 'cltbld', 16:12:54 INFO - 'MAIL': '/var/mail/cltbld', 16:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:54 INFO - 'MOZ_NO_REMOTE': '1', 16:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:54 INFO - 'NO_EM_RESTART': '1', 16:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:54 INFO - 'PWD': '/builds/slave/test', 16:12:54 INFO - 'SHELL': '/bin/bash', 16:12:54 INFO - 'SHLVL': '1', 16:12:54 INFO - 'TERM': 'linux', 16:12:54 INFO - 'TMOUT': '86400', 16:12:54 INFO - 'USER': 'cltbld', 16:12:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:54 INFO - '_': '/tools/buildbot/bin/python'} 16:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:55 INFO - Downloading/unpacking jsonschema 16:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:12:56 INFO - Downloading/unpacking functools32 (from jsonschema) 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12: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 16:12:57 INFO - Downloading functools32-3.2.3-2.tar.gz 16:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:12:57 INFO - warning: no files found matching '*.txt' 16:12:57 INFO - no previously-included directories found matching 'build' 16:12:57 INFO - no previously-included directories found matching 'dist' 16:12:57 INFO - no previously-included directories found matching '.git*' 16:12:57 INFO - Installing collected packages: jsonschema, functools32 16:12:57 INFO - Running setup.py install for functools32 16:12:57 INFO - warning: no files found matching '*.txt' 16:12:57 INFO - no previously-included directories found matching 'build' 16:12:57 INFO - no previously-included directories found matching 'dist' 16:12:57 INFO - no previously-included directories found matching '.git*' 16:12:57 INFO - Successfully installed jsonschema functools32 16:12:57 INFO - Cleaning up... 16:12:57 INFO - Return code: 0 16:12:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:57 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 0x7fbcbd12ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13d48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x149f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:12:57 INFO - Using env: {'DISPLAY': ':0', 16:12:57 INFO - 'HOME': '/home/cltbld', 16:12:57 INFO - 'LANG': 'en_US.UTF-8', 16:12:57 INFO - 'LANGUAGE': 'en_US:en', 16:12:57 INFO - 'LOGNAME': 'cltbld', 16:12:57 INFO - 'MAIL': '/var/mail/cltbld', 16:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:57 INFO - 'MOZ_NO_REMOTE': '1', 16:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:12:57 INFO - 'NO_EM_RESTART': '1', 16:12:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:57 INFO - 'PWD': '/builds/slave/test', 16:12:57 INFO - 'SHELL': '/bin/bash', 16:12:57 INFO - 'SHLVL': '1', 16:12:57 INFO - 'TERM': 'linux', 16:12:57 INFO - 'TMOUT': '86400', 16:12:57 INFO - 'USER': 'cltbld', 16:12:57 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:57 INFO - '_': '/tools/buildbot/bin/python'} 16:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:57 INFO - Downloading/unpacking flake8 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:12:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:12:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:13:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:13:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:13:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:13:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:13:00 INFO - no previously-included directories found matching 'docs/_build' 16:13:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:13:00 INFO - Running setup.py install for pep8 16:13:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:13:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:13:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:13:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:13:00 INFO - no previously-included directories found matching 'docs/_build' 16:13:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:13:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:13:00 INFO - Cleaning up... 16:13:00 INFO - Return code: 0 16:13:00 INFO - Running post-action listener: _resource_record_post_action 16:13:00 INFO - Running post-action listener: _start_resource_monitoring 16:13:00 INFO - Starting resource monitoring. 16:13:00 INFO - ##### 16:13:00 INFO - ##### Running install step. 16:13:00 INFO - ##### 16:13:00 INFO - Running pre-action listener: _resource_record_pre_action 16:13:00 INFO - Running main action method: install 16:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:00 INFO - Reading from file tmpfile_stdout 16:13:00 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:00 INFO - Reading from file tmpfile_stdout 16:13:00 INFO - Output received: 16:13:00 INFO - Usage: mozinstall [options] installer 16:13:00 INFO - Options: 16:13:00 INFO - -h, --help show this help message and exit 16:13:00 INFO - -d DEST, --destination=DEST 16:13:00 INFO - Directory to install application into. [default: 16:13:00 INFO - "/builds/slave/test"] 16:13:00 INFO - --app=APP Application being installed. [default: firefox] 16:13:00 INFO - mkdir: /builds/slave/test/build/application 16:13:00 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'] 16:13:00 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 16:13:17 INFO - Reading from file tmpfile_stdout 16:13:17 INFO - Output received: 16:13:17 INFO - /builds/slave/test/build/application/firefox/firefox 16:13:17 INFO - Running post-action listener: _resource_record_post_action 16:13:17 INFO - ##### 16:13:17 INFO - ##### Running run-tests step. 16:13:17 INFO - ##### 16:13:17 INFO - Running pre-action listener: _resource_record_pre_action 16:13:17 INFO - Running main action method: run_tests 16:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:17 INFO - Python 2.7.3 16:13:17 INFO - Return code: 0 16:13:17 INFO - grabbing minidump binary from tooltool 16:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:13:17 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 0x152e410>, '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 0x152f310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152f7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:13:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:13:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:13:17 INFO - Return code: 0 16:13:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:13:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:13:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:13:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:13:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:13:18 INFO - Return code: 0 16:13:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:13:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:13:18 INFO - Using env: {'DISPLAY': ':0', 16:13:18 INFO - 'HOME': '/home/cltbld', 16:13:18 INFO - 'LANG': 'en_US.UTF-8', 16:13:18 INFO - 'LANGUAGE': 'en_US:en', 16:13:18 INFO - 'LOGNAME': 'cltbld', 16:13:18 INFO - 'MAIL': '/var/mail/cltbld', 16:13:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:13:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:18 INFO - 'MOZ_NO_REMOTE': '1', 16:13:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:13:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:13:18 INFO - 'NO_EM_RESTART': '1', 16:13:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:18 INFO - 'PWD': '/builds/slave/test', 16:13:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:13:18 INFO - 'SHELL': '/bin/bash', 16:13:18 INFO - 'SHLVL': '1', 16:13:18 INFO - 'TERM': 'linux', 16:13:18 INFO - 'TMOUT': '86400', 16:13:18 INFO - 'USER': 'cltbld', 16:13:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292', 16:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:18 INFO - '_': '/tools/buildbot/bin/python'} 16:13:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:13:18 INFO - mozversion application_buildid: 20160418151421 16:13:18 INFO - mozversion application_changeset: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 16:13:18 INFO - mozversion application_display_name: Nightly 16:13:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:13:18 INFO - mozversion application_name: Firefox 16:13:18 INFO - mozversion application_remotingname: firefox 16:13:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:13:18 INFO - mozversion application_vendor: Mozilla 16:13:18 INFO - mozversion application_version: 48.0a1 16:13:18 INFO - mozversion platform_buildid: 20160418151421 16:13:18 INFO - mozversion platform_changeset: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 16:13:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:13:18 INFO - mozversion platform_version: 48.0a1 16:13:18 INFO - using testdate: 1461021198 16:13:18 INFO - actual date: 1461021198 16:13:18 INFO - starting webserver on 'localhost:36749' 16:13:18 INFO - SUITE-START | Running 2 tests 16:13:18 INFO - TEST-START | dromaeo_css 16:13:18 INFO - operating with platform_type : linux_ 16:13:18 INFO - Initialising browser for dromaeo_css test... 16:13:18 INFO - TEST-INFO | started process 12720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLXbYlZ/profile http://localhost:36749/getInfo.html) 16:13:24 INFO - PROCESS | 12720 | __metrics Screen width/height:1600/1200 16:13:24 INFO - PROCESS | 12720 | colorDepth:24 16:13:24 INFO - PROCESS | 12720 | Browser inner width/height: 1024/697 16:13:24 INFO - PROCESS | 12720 | __metrics 16:13:25 ERROR - PROCESS | 12720 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:13:27 INFO - TEST-INFO | 12720: exit 0 16:13:27 INFO - Browser initialized. 16:13:27 INFO - Running cycle 1/1 for dromaeo_css test... 16:13:27 INFO - TEST-INFO | started process 12803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLXbYlZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:13:38 INFO - PROCESS | 12803 | RSS: Main: 154521600 16:13:38 INFO - PROCESS | 12803 | 16:14:30 INFO - PROCESS | 12803 | 1461021270029 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:16:42 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36749/tests/dromaeo/cssquery-ext.html) 16:16:43 INFO - PROCESS | 12803 | RSS: Main: 246304768 16:16:43 INFO - PROCESS | 12803 | 16:19:45 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-ext.html (next: http://localhost:36749/tests/dromaeo/cssquery-jquery.html) 16:19:46 INFO - PROCESS | 12803 | RSS: Main: 230187008 16:19:46 INFO - PROCESS | 12803 | 16:22:48 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36749/tests/dromaeo/cssquery-mootools.html) 16:22:49 INFO - PROCESS | 12803 | RSS: Main: 234242048 16:22:49 INFO - PROCESS | 12803 | 16:25:51 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36749/tests/dromaeo/cssquery-prototype.html) 16:25:52 INFO - PROCESS | 12803 | RSS: Main: 223887360 16:25:52 INFO - PROCESS | 12803 | 16:28:55 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36749/tests/dromaeo/cssquery-yui.html) 16:28:55 INFO - PROCESS | 12803 | RSS: Main: 217329664 16:28:55 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | Cycle 1(1): loaded http://localhost:36749/tests/dromaeo/cssquery-yui.html (next: http://localhost:36749/tests/dromaeo/cssquery-dojo.html) 16:31:58 INFO - PROCESS | 12803 | RSS: Main: 312385536 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | __start_tp_report 16:31:58 INFO - PROCESS | 12803 | _x_x_mozilla_page_load 16:31:58 INFO - PROCESS | 12803 | _x_x_mozilla_page_load_details 16:31:58 INFO - PROCESS | 12803 | |i|pagename|runs| 16:31:58 INFO - PROCESS | 12803 | |0;dojo.html;2591.8833652485637;2683.2621029216975;2683.510651588369;2694.3129501977005;2714.5113879501682;4461.107778444308;4532.116237333721;4603.2404653232215;4610.861674149776;4636.953630463699;4562.680612357136;4584.816607335711;4642.257238841786;4682;4712;4242.512111107224;4252.489701235856;4261.424707664457;4293.098449325646;4313.633341166004;4323;4339.41417908583;4385.057212699269;4388.42950416447;4397.318415645564;4153.937690934638;4231.217224813388;4241.491021077491;4290.978545107255;4350.782460876943;4197.559256278078;4215.747055176699;4217.683673724483;4222.071144348239;4227.365895115727;4176.561461046607;4205.137946720914;4206.47419072616;4209.97895010526;4268.0823622921225;4492.281235002384;4503.572160644733;4523.751193684346;4551.0215303709865;4573.062522182944;4710.646701497401;4959.256111583255;4960.826371077031;4962.60299176065;4997.05056039355;4950.777215025332;4951.455339912643;4992.750362481897;5004.099262132815;5038.647294689373;4569.725816450957;4613.077384523109;4622.329762184464;4623.260278355447;4659.860204193879;4965.925511117336;5034.924476132861;5043.394792624908;5053.646244762905;5062.7468626568525;4880.8779780505065;4897.14299997499;4905.3132561441425;4916.655834091579;4955.603551715854;4968.080905032581;4970.8757281068265;4990.8752281193265;4992.625553083533;5041.747912604355;4762.618990480753;4865.878353041202;4910;4955.157623204064;4973.825916092919;4936.210206367035;4952.182889823139;4953.950460495349;4955.578775804025;4960.677555958851;4189.575544314947;4190.413342132104;4197.391378250137;4209.17921005401;4234.894127646833;3512.5609298837644;3648.9817550912076;3661.3409586274192;3671.6328367163605;3758.548974123068;4340.934885976713;4358.5205627380965;4361.1495758327455;4365.454318210223;4409.030013397048;5011.473795251446;5046.34397528319;5048.091343558195;5051.191809310492;5098.770555325019;4198.6641068714425;4260.936085958713;4326.978365108154;4330;4352.542982986804;4404.955950440519;4423.977880110579;4428.402165707653;4452.4211852458975;4468.329750537446;4701.318308845267;4702.365180700698;4704.929426058543;4705.7411842349;4753.881152971203;4882.829100981448;4950.702957822542;4956.752162391794;5027.4218464877;5047.444781074006;4734.928976065431;4767.284907263938;4775.904481910412;4783.736894470838;4823.493533179106;4935;4936.062148191832;4937.925931111108;4942.4810394909455;4962.454130045764;4780.378550788375;4963.255511673278;5005.174146265826;5018.272350509083;5051.823186188466;4807.966287248259;4812.903741925072;4812.951870481251;4845.5881250092725;4858.732769697701;4783.282507623884;4784.378030855966;4787.593054590398;4793.448753393404;4843.128236917248;4615;4733.36099626546;4754.548317909793;4806.086843499724;4837.830675926326;4771.045790841776;4783.593394561502;4791.784369703441;4793.352897358952;4834.347363105936;4684.6486513510945;4694.061187762393;4697.459792123949;4733.242681170997;4749.430068391815 16:31:58 INFO - PROCESS | 12803 | |1;ext.html;574.2477354665386;599.7630935780367;605.3855336833116;614.6957256158203;618.6504624886938;26622.733772662268;34180.487292690595;35230.8238458808;35262;35278.29443411133;36674.44988325177;37537.81231093841;37575.436368454495;37651;37655.811720941354;7652.1582625911105;7781.182975787546;7832.255935686101;7837.333826624744;7854.136045035041;7772.80567985802;7839.843203135933;7841.764747057598;7874.212578742108;7898.684052637887;7806.492577982442;7863.488873223251;7880.842383152333;7901.762947111596;7970.123286438487;7844.843103137905;7853.41099417546;7857.882131768057;7860.213978602123;7929.484583502082;7709.922900770976;7818.569978651172;7821.530708157528;7822.765317040498;7830;37131.443028354595;37502.624973750186;37510.43734343987;37563.060923476856;37594;2983.4778913690016;3169.762267829922;3197.8560964756643;3452.5684289463816;3709.2025214578725;28389.85805070982;28428.71571284302;28779.424411511864;29228.41543169146;29239;3218.7103160715415;3245.383377158332;3269.75476839238;3295.423300922353;3395.7453191010773;199128;200236;200838;201156.99999999854;202223;50858.745706271235;52059.73970130201;52532.47467525352;52534;52536.73731631394;54613.72693136509;59310.70344648249;61211.08183377253;61480.69259653763;61586.38413615896;14530.782038269434;14688;14777;14779;14788.630284242765;11105.722356941142;11262.267952583055;11283.887161128448;11327.320360778214;11376.829347559793;17908.91045544764;17976.37082702099;18047.909760451115;18100.366487172887;18607.44177674672;6676.123592699527;6809.931900681029;7046.189688185822;7051.294870512907;7134.92865071353;859.6819176904586;923.9953800230955;934.7289286107089;1008.7528555503802;1062.654637242899;26249.475010499682;27881;28207.858960705064;28300.292492687437;28370.43259134847;992.6178421307849;1004.829179039565;1007.3150257824603;1025.6307729217476;1090.2150451674781;14480.710385792225;14845.851541484446;15057.924710376597;15150.848491515164;15326.923365383102;322500;323199;328402;328609;329774;47562.76218618885;48512.27231591529;48559.757201214474;48605.02789944181;48706.75646621744;1124.4153040418937;1137.3119262846024;1160.1762748448527;1228.5761412312916;1236.097648716442;26409.735902641496;27246.455070898868;27262.318442039108;27970.72029279681;27988.30029249285;38272;38910;38966.4155037669;38979.80510097545;39073.413898790976;212658;215634;215690;215954;216255;25210.117645882307;37766.24467510689;39159.6084039156;39291.21417571575;39698;37234.25531489409;38512.22975540417;39012.609873900896;39291;39461.40807887827;33380.66619333873;33395.332093358484;34007;34397.48403773995;34784.65215347814;33130.834345828116;33174.17064573405;33211.50182747309;33467;33490;33565.49651755275;34195;34327.485087724206;34530.65469345274;34640.65359346374;29122.56316155302;30386.39227215399;30505.84747076339;30560;31091.222719432197;26020.8698956504;27214.183574492796;27250.318742030813;27456.862715686293;27490.450190996467 16:31:58 INFO - PROCESS | 12803 | |2;jquery.html;375.83275442428123;383.29856362855975;384.3984164782116;388.6405075305342;394.2332163941136;15712.428757124297;17237.655246895054;17628.535154102236;17884;17888.373906913228;17068.82931170688;17334.739978900325;17690.203940822663;17932;17939.282428702867;4905.75471226439;5046.84859454217;5075.5178258065425;5096.464871188509;5096.541311281984;3640.5631324240994;3785.6403641654138;5322.88219473909;5959;5967.701614919259;5715.71421428929;5926.822195334125;5931.911021334662;5958.493528050121;5976.820695379124;4995.525425084611;5293.523582877559;5298.814541491068;5307.681539107666;5948.167256584082;3331.750118741104;5025.447200807908;5133.666311689728;5362.329708786402;5374.381946076212;12198.939005304917;12287.56993505232;12374.876251237463;13744.587662370144;20707;1790.4897104325335;1811.8641101917408;1929.4404622659413;1991.412533302679;2209.103217585211;8831.425957312755;8907.688230911886;9514;9711.36876103058;9741.853872191923;1612.2986500872157;1617.8948368356018;1730.5327561558436;1735.9826401736073;1770.371518111065;62687.68656156736;65978;67147.66426167887;67812.32187678159;69407.65296173487;10150.593976241016;10175.7456063599;10312.329698569569;10342;10355.534000969823;14175.929120354333;14237.857621423653;14272.928635356757;14305.141691498544;14319.498817541544;7190.416740978576;7413.666385012687;7528.9835872157655;7558.470907036454;7740.303372696371;6841.486888483284;6966.12923384577;6967.895481567781;7115.786526404217;7240.855182896313;4299;4418.182636212311;4469.955300447019;4500.819967201341;4536.296873984472;1798.0781230500436;1877.1433128638735;1887.7923428422862;1902.0869982408522;1912.1682068300333;841.066416277931;858.8754630578532;862.6980556805069;910.5902343945119;954.9331546791658;16072.75890861638;16432;16673.24970376336;16873.578160546083;17177.31290748356;827.7640872351351;871;898.5821592959305;932.128459890005;933.1461712533091;9249.583768730423;9318.72043838686;10074.899251007397;10136.2904596679;10158.288919775692;409963;413017;419026;419647;419707;53828;78245.21754782379;78546;82803;85498.5725071383;878.1131057631837;947.6114792934864;958.6357184269933;980.8724865767405;994.7612572982576;9516.524173791422;9698.806023879342;9774.31579789408;10094.444805535766;10283.640072597424;32778.50832237566;32810.835945820116;33191.17022074371;33422.33155336928;35501.467477988364;48206;49687.75156124196;49720.502794973036;49836.75081624569;50362;6639.601623902388;8870.42342247752;10678.359298442118;11230.66308010761;11714.765704685688;10489.53146559467;11318.830217546578;11960.222585531912;12233.266004039786;12466.376681166086;9227.569726692373;10222.59109635636;10962.177836662442;11173.776524469302;11440.313581185155;10694.111351158528;10828.404437755999;11049.39228342449;11136.443177841238;11478.311301321948;11242.10063194926;11286.379249141377;11293.209475336977;11477.713057173638;11604.6518604442;11120.833187502032;11151.72120696989;11226.326420414802;11264.94367528157;11299.604513841976;11119.499622516845;11202.32786032808;11291.378974156172;11332.660020199408;11479.655610331703 16:31:58 INFO - PROCESS | 12803 | |3;mootools.html;1263.684078980255;1430.8426073131955;1460.9050411723238;1589.7376932806087;1789.016041177353;6779.898301525475;7573.621318934059;7807.180246074166;7821.687132514707;7821.726239581609;7691.807704807383;7803.5708036057995;7910.644021019054;7919.247671471216;7921.762347129596;2250.1112060736;2283.2579411691186;2284.0407029047838;2285.5543169082034;2286.7827556382117;2236.4297104238394;2260.547890421922;2297.2993237062688;2297.310806757966;2298.5517824024237;2207.2385027165683;2225.3991422316044;2231.977680223193;2254.3462395905167;2257.063318722728;2222.744384395799;2234;2236.910523579055;2239.9216027439043;2242.708447901762;2199.549092436047;2205.38249290199;2214.3467677035314;2220.378294077661;2221.766714494971;7676.347510461617;7706.845863082707;7852.3325517331095;7858.292753652198;7953.642086106139;4512.909741805145;4652.069586082797;4671.205894997858;4671.88320291992;4674.836380726692;2931.1646180838356;2950.100219433072;2976.657684366304;2987.8944790427463;2997.9400411991637;912.0833562269943;925.7500474871814;929.7954450021052;946.6402766948585;956;10221.284510084297;10299.691009269734;10324.587016519405;10327.431991240479;10414;3980.3631418972827;4077.836886524565;4186.6859985501205;4236.110416812504;4237.597428244312;8175.959120204361;8225;8252.669893204325;8260.586970651444;8266.586670666442;4581.060882519142;4592.563706447882;4593.035462552902;4602.723836569749;4617.676762626584;3722.758020728644;4030.5767894371256;4114.033202197481;4151.8962025949595;4223.303154979455;3498.510208570802;3529.5940966788635;3567.3222087803233;3607.801570913625;3649.817509124533;984.7931934293736;1103.227740581596;1107.6510899066768;1107.7396811749231;1108.0692218536308;725.6734469488752;763.7097902796902;770.7379490973096;836.0928392693874;874.1608056265928;5185.429602743695;5234.3718753749035;5459.836204913859;5477.096279113902;5496.367917689437;534.8582625604218;541.1476923844959;544.2625242796033;552.9889402211933;662.2715013485224;3753.2868754936476;3780.432935059763;3850.7111966602056;3873.302805495038;3884.941725874114;9388.62445502186;9779;9823.901760982299;9832;9832.655857045112;9794.167495762798;9796.412215267106;9807.901920980841;9808.558614862348;9812.067853553901;7092;7308.853822923512;7331.596762178025;7344.742933997284;7369.1525474570035;5290.206469029677;5320.255164276927;5416.295881535375;5424.83725488236;5650.180723795109;8614.870776938225;8681.91318086811;8788.03331633507;8794.516301603473;8824.249938755278;8518.083431227924;8782.077881822317;8933.240674542476;9028.864567031631;9034.367594268339;6381.361863813581;7192.964035179791;7259.782206533812;7408.888866667112;7436.73971410998;7430.3684186842565;7466.925330746623;7474.738384156529;7499.287567681062;7524.097108347033;6513.674316284072;6646.302138275411;6684.532082754355;6763.019362192356;6818.863622727418;6592.80221593353;6608.4052435281055;6617.867642647024;6718.56329338611;6798.082258894986;6359.936400636119;6467.967660161858;6511;6534.150560427114;6551.344865513411;4476.2837945928495;4553.567411095941;4566.178087944202;4598.678092533624;4734.97632511849;4539.092181563767;4588.885277867947;4732.313814496947;4787.593054590398;4803.447603525639 16:31:58 INFO - PROCESS | 12803 | |4;prototype.html;293.4732155780374;310.9253779093017;311.91734190439536;313.7725149266781;316.6943899137332;4960.702357858531;4984.311609409038;5030.547250747432;5039.168537191368;5043.31915191449;4682.227432473638;4728.763561821912;4835.105505481489;4888.853334399965;4965.70205787653;3890.144168282973;3901.8634347797833;3911.921761564774;3936.5079365079364;3940.4483372327895;3903.8048097595147;3922.313595120857;3925.941110883339;3933.075727204119;3945.8618948336816;3622.4385220290765;3702.5556933167923;3816.847326106966;3830.4828848105567;3895;3893.279743247508;3904.297226499229;3910.2179564087155;3916.3929590913453;3917.7061720371084;3449.58604967403;3465.324261768956;3469.6356882527225;3477.078574177845;3489.668481494254;4722.362481065081;4765.95829413144;4795.184818580849;4823.421189457287;4840.927386089212;4459.487158976727;4497.572730590588;4498.077894031716;4505.414296141481;4507.076049409863;5178.896422071537;5193.844184674465;5194.220866869962;5195.3246078009615;5198.896022079537;3513.8945831624837;3806.5812760596314;3814.389697648363;3817.6373244541724;3818.4463252828464;5379.38137114233;5437.619366644297;5456.290682211326;5477.904983684245;5485.862853428656;3730;3750.662440380379;3753.887383378503;3759.191773768653;3759.6240375962184;3831.3103641344824;3839.404892241707;3844.826982785781;3847.942280865789;3848.615138486129;4137.8965525862095;4342.522322544518;4345.022369966732;4348.978255108705;4363.149185908781;5162.483751624807;5162.6128040397125;5182.740862956837;5185.559227465707;5195.636305458582;1323.4309247023873;1327.9734405312033;1342.174562643967;1343.2007955266602;1343.9932800335935;838.7106448275457;870.6822009966317;898.1063841477641;905.5698543191932;906.7551761024619;4570.862874113782;4574.153781550423;4574.931376029363;4586.564276393738;4593.127305811885;5319.840404787863;5348.893022139536;5351.09031464652;5360.16917377807;5371.328583927009;4412.624926881187;4588.862334129982;4592.655550833634;4621.768911554476;4636.976815115903;2809.339805145789;2817.2534278416238;2834.9858250708617;2843.1470558832266;2861.570764385359;5009.499050095034;5016.799328026911;5028.069807085701;5029.899402011939;5050.903874891952;5233.555147812479;5413.079776438015;5431.076716958127;5440.3471583409455;5441.143399715181;4991.450940396553;5016.949830501649;5026.698398096126;5034.924476132861;5040.2691609716385;5278.709670968134;5282.735863206901;5285.207218917193;5292.708901010481;5294.311739473844;5022.648414610942;5159.896802064013;5198.532132108128;5201.037808005531;5207.661502002509;5004.824831130893;5013.473585273639;5015.824446144367;5021.447640759587;5021.924671129863;3706.2402207547925;3736.1220113272816;3742.326381251401;3750.94373584391;3755.1926335837925;5200.765965531485;5305.098133317269;5436.565074794086;5449.773800894767;5485.917711234255;5274.841754747364;5295.76169072385;5307.7611507483025;5311.946880531145;5313.601479888946;5113.616478764033;5229.084910140634;5280.313559237275;5282.709450980233;5310.9468905312;4920.039368308546;5010.549050585465;5177.171652535576;5182.274481572658;5215.426303106731;5290.259363689163;5311.920321195108;5313.096773548429;5315.6279060465395;5330.600204984564;5189.325387699575;5193.169092945111;5196.194589838581;5203.453637368022;5217.478252174752 16:31:58 INFO - PROCESS | 12803 | |5;yui.html;631.6304961597466;650.9056186852904;653.4674240493997;670.1991120610872;728.5519405565577;11288.153388495864;11448.427578621067;11608.941955290233;11648.068154547636;11655.533778648854;11442.485088171032;11629.651110466699;12117.091218158652;12144.453499592539;12158.513659453632;638.907358433027;778.7196609220705;813.6663967773229;814.0150417994234;827.4001349021971;803.9598020099;805.1586092533288;809.0816922792643;811.2171754257141;811.4157806379397;837.1042984007115;842.3261390887296;842.4945032980224;843.8227972125861;844.1010324004945;668.0280192320187;822.5352675738249;830.3191383065858;845.344857735254;845.6997765793117;622.4491325177205;622.707327556048;623.1929651101789;623.6258245052977;623.7037407231529;11068.723281917935;11102;11105.500252488657;11116.666500005012;11116.722081948015;12228.571999980048;12692.739205115284;12798.232106073572;12904.87095129046;13115.934420327838;9373.812523749486;9408.623655053789;9506.334556581041;9532.761680958032;9595.472249026301;3301.0591981285456;3307.4873394623874;3368.4947257911263;3416.829158542088;3472.8958131256104;44327.335089973676;44447.77776111131;44453.333200002024;44518;44793.32810007853;9967.352122112039;10040;10191.694249172537;10583.94708026455;10854.565817367375;18270.63458730818;18316.63366732658;18319.084045797656;18457.723134153;18959.146838392306;6648.03603477493;6696.8995465068065;6714.932850671528;6746.359095885883;6780.796576102724;3680.576733675608;3718.88843334695;3742.5883152853166;3775.339315619756;3840.135969406917;2552.2470871093074;2640.9735902641114;2647.179374393944;2653.840769553833;2656.6147908553153;467.47409164689725;479.1519011349891;488.2334734466924;489.2709862305211;490.448245723561;596.7791916990741;604.703695189354;612.9049997250434;622.1197006236216;644.2751904107879;7315.926840731631;8046.839063218703;8062.39532035088;8147.266745992889;8288.295494882881;516.9121249387613;535.4939582094961;593.3325009364465;598.141666708275;600.6576251536583;3748.8125593720206;3752.442219449227;3777.697784177259;3806.873245036415;3808.961910380916;51398;51817.7409112952;51870.74064629653;51946.740266298424;52199.73900130475;44031.779841100586;44464.777676111415;44792;45407;45504.317435238496;40913.59086409098;41517.58482415198;41596;41900.37149442761;41962.37056444156;7118.323759242864;7289.854202916018;7449.404047676281;7538.736144234926;7559.470837041355;27406.177814665592;27429.17712468629;27440.17679469619;28328.291792705346;28352.716472835833;33826;35235.64764352324;38006;38428;38911;9483.620655173716;9727.65953191635;9742.75643108928;9895.356801807859;9909.405435673883;10233.902050079567;10413.687589372332;10510.106640935606;10971.067459266053;11033.613823516138;8949.239314658327;8976.237019853386;9000.36997410175;9367.250619950524;9443.480608566595;8766.824663506823;8791.34064945119;9043.502607356659;9088.545572721205;9181;9135.177833994972;9148.085191480799;9258.074192580689;9297.628094876129;9515.096065873731;8100.513969161869;8212.507249565046;8319.625616847135;8533.53065581399;8799.912000880166;7882.763517094496;8291.626876790438;8351.624176911931;8408.831823363376;8477.703280385156 16:31:58 INFO - PROCESS | 12803 | __end_tp_report 16:31:58 INFO - PROCESS | 12803 | __start_cc_report 16:31:58 INFO - PROCESS | 12803 | _x_x_mozilla_cycle_collect,928 16:31:58 INFO - PROCESS | 12803 | __end_cc_report 16:31:58 INFO - PROCESS | 12803 | __startTimestamp1461022318508__endTimestamp 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | ------- Summary: start ------- 16:31:58 INFO - PROCESS | 12803 | Number of tests: 6 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#0] dojo.html Cycles:165 Average:4584.53 Median:4710.65 stddev:471.57 (10.0%) stddev-sans-first:446.36 16:31:58 INFO - PROCESS | 12803 | Values: 2591.9 2683.3 2683.5 2694.3 2714.5 4461.1 4532.1 4603.2 4610.9 4637.0 4562.7 4584.8 4642.3 4682.0 4712.0 4242.5 4252.5 4261.4 4293.1 4313.6 4323.0 4339.4 4385.1 4388.4 4397.3 4153.9 4231.2 4241.5 4291.0 4350.8 4197.6 4215.7 4217.7 4222.1 4227.4 4176.6 4205.1 4206.5 4210.0 4268.1 4492.3 4503.6 4523.8 4551.0 4573.1 4710.6 4959.3 4960.8 4962.6 4997.1 4950.8 4951.5 4992.8 5004.1 5038.6 4569.7 4613.1 4622.3 4623.3 4659.9 4965.9 5034.9 5043.4 5053.6 5062.7 4880.9 4897.1 4905.3 4916.7 4955.6 4968.1 4970.9 4990.9 4992.6 5041.7 4762.6 4865.9 4910.0 4955.2 4973.8 4936.2 4952.2 4954.0 4955.6 4960.7 4189.6 4190.4 4197.4 4209.2 4234.9 3512.6 3649.0 3661.3 3671.6 3758.5 4340.9 4358.5 4361.1 4365.5 4409.0 5011.5 5046.3 5048.1 5051.2 5098.8 4198.7 4260.9 4327.0 4330.0 4352.5 4405.0 4424.0 4428.4 4452.4 4468.3 4701.3 4702.4 4704.9 4705.7 4753.9 4882.8 4950.7 4956.8 5027.4 5047.4 4734.9 4767.3 4775.9 4783.7 4823.5 4935.0 4936.1 4937.9 4942.5 4962.5 4780.4 4963.3 5005.2 5018.3 5051.8 4808.0 4812.9 4813.0 4845.6 4858.7 4783.3 4784.4 4787.6 4793.4 4843.1 4615.0 4733.4 4754.5 4806.1 4837.8 4771.0 4783.6 4791.8 4793.4 4834.3 4684.6 4694.1 4697.5 4733.2 4749.4 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#1] ext.html Cycles:180 Average:41196.28 Median:34187.74 stddev:66380.72 (194.2%) stddev-sans-first:66496.87 16:31:58 INFO - PROCESS | 12803 | Values: 574.2 599.8 605.4 614.7 618.7 26622.7 34180.5 35230.8 35262.0 35278.3 36674.4 37537.8 37575.4 37651.0 37655.8 7652.2 7781.2 7832.3 7837.3 7854.1 7772.8 7839.8 7841.8 7874.2 7898.7 7806.5 7863.5 7880.8 7901.8 7970.1 7844.8 7853.4 7857.9 7860.2 7929.5 7709.9 7818.6 7821.5 7822.8 7830.0 37131.4 37502.6 37510.4 37563.1 37594.0 2983.5 3169.8 3197.9 3452.6 3709.2 28389.9 28428.7 28779.4 29228.4 29239.0 3218.7 3245.4 3269.8 3295.4 3395.7 199128.0 200236.0 200838.0 201157.0 202223.0 50858.7 52059.7 52532.5 52534.0 52536.7 54613.7 59310.7 61211.1 61480.7 61586.4 14530.8 14688.0 14777.0 14779.0 14788.6 11105.7 11262.3 11283.9 11327.3 11376.8 17908.9 17976.4 18047.9 18100.4 18607.4 6676.1 6809.9 7046.2 7051.3 7134.9 859.7 924.0 934.7 1008.8 1062.7 26249.5 27881.0 28207.9 28300.3 28370.4 992.6 1004.8 1007.3 1025.6 1090.2 14480.7 14845.9 15057.9 15150.8 15326.9 322500.0 323199.0 328402.0 328609.0 329774.0 47562.8 48512.3 48559.8 48605.0 48706.8 1124.4 1137.3 1160.2 1228.6 1236.1 26409.7 27246.5 27262.3 27970.7 27988.3 38272.0 38910.0 38966.4 38979.8 39073.4 212658.0 215634.0 215690.0 215954.0 216255.0 25210.1 37766.2 39159.6 39291.2 39698.0 37234.3 38512.2 39012.6 39291.0 39461.4 33380.7 33395.3 34007.0 34397.5 34784.7 33130.8 33174.2 33211.5 33467.0 33490.0 33565.5 34195.0 34327.5 34530.7 34640.7 29122.6 30386.4 30505.8 30560.0 31091.2 26020.9 27214.2 27250.3 27456.9 27490.5 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#2] jquery.html Cycles:180 Average:24772.09 Median:2084.47 stddev:68488.05 (3285.6%) stddev-sans-first:68655.68 16:31:58 INFO - PROCESS | 12803 | Values: 375.8 383.3 384.4 388.6 394.2 15712.4 17237.7 17628.5 17884.0 17888.4 17068.8 17334.7 17690.2 17932.0 17939.3 4905.8 5046.8 5075.5 5096.5 5096.5 3640.6 3785.6 5322.9 5959.0 5967.7 5715.7 5926.8 5931.9 5958.5 5976.8 4995.5 5293.5 5298.8 5307.7 5948.2 3331.8 5025.4 5133.7 5362.3 5374.4 12198.9 12287.6 12374.9 13744.6 20707.0 1790.5 1811.9 1929.4 1991.4 2209.1 8831.4 8907.7 9514.0 9711.4 9741.9 1612.3 1617.9 1730.5 1736.0 1770.4 62687.7 65978.0 67147.7 67812.3 69407.7 10150.6 10175.7 10312.3 10342.0 10355.5 14175.9 14237.9 14272.9 14305.1 14319.5 7190.4 7413.7 7529.0 7558.5 7740.3 6841.5 6966.1 6967.9 7115.8 7240.9 4299.0 4418.2 4470.0 4500.8 4536.3 1798.1 1877.1 1887.8 1902.1 1912.2 841.1 858.9 862.7 910.6 954.9 16072.8 16432.0 16673.2 16873.6 17177.3 827.8 871.0 898.6 932.1 933.1 9249.6 9318.7 10074.9 10136.3 10158.3 409963.0 413017.0 419026.0 419647.0 419707.0 53828.0 78245.2 78546.0 82803.0 85498.6 878.1 947.6 958.6 980.9 994.8 9516.5 9698.8 9774.3 10094.4 10283.6 32778.5 32810.8 33191.2 33422.3 35501.5 48206.0 49687.8 49720.5 49836.8 50362.0 6639.6 8870.4 10678.4 11230.7 11714.8 10489.5 11318.8 11960.2 12233.3 12466.4 9227.6 10222.6 10962.2 11173.8 11440.3 10694.1 10828.4 11049.4 11136.4 11478.3 11242.1 11286.4 11293.2 11477.7 11604.7 11120.8 11151.7 11226.3 11264.9 11299.6 11119.5 11202.3 11291.4 11332.7 11479.7 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#3] mootools.html Cycles:180 Average:5128.73 Median:5420.57 stddev:2840.18 (52.4%) stddev-sans-first:2833.29 16:31:58 INFO - PROCESS | 12803 | Values: 1263.7 1430.8 1460.9 1589.7 1789.0 6779.9 7573.6 7807.2 7821.7 7821.7 7691.8 7803.6 7910.6 7919.2 7921.8 2250.1 2283.3 2284.0 2285.6 2286.8 2236.4 2260.5 2297.3 2297.3 2298.6 2207.2 2225.4 2232.0 2254.3 2257.1 2222.7 2234.0 2236.9 2239.9 2242.7 2199.5 2205.4 2214.3 2220.4 2221.8 7676.3 7706.8 7852.3 7858.3 7953.6 4512.9 4652.1 4671.2 4671.9 4674.8 2931.2 2950.1 2976.7 2987.9 2997.9 912.1 925.8 929.8 946.6 956.0 10221.3 10299.7 10324.6 10327.4 10414.0 3980.4 4077.8 4186.7 4236.1 4237.6 8176.0 8225.0 8252.7 8260.6 8266.6 4581.1 4592.6 4593.0 4602.7 4617.7 3722.8 4030.6 4114.0 4151.9 4223.3 3498.5 3529.6 3567.3 3607.8 3649.8 984.8 1103.2 1107.7 1107.7 1108.1 725.7 763.7 770.7 836.1 874.2 5185.4 5234.4 5459.8 5477.1 5496.4 534.9 541.1 544.3 553.0 662.3 3753.3 3780.4 3850.7 3873.3 3884.9 9388.6 9779.0 9823.9 9832.0 9832.7 9794.2 9796.4 9807.9 9808.6 9812.1 7092.0 7308.9 7331.6 7344.7 7369.2 5290.2 5320.3 5416.3 5424.8 5650.2 8614.9 8681.9 8788.0 8794.5 8824.2 8518.1 8782.1 8933.2 9028.9 9034.4 6381.4 7193.0 7259.8 7408.9 7436.7 7430.4 7466.9 7474.7 7499.3 7524.1 6513.7 6646.3 6684.5 6763.0 6818.9 6592.8 6608.4 6617.9 6718.6 6798.1 6359.9 6468.0 6511.0 6534.2 6551.3 4476.3 4553.6 4566.2 4598.7 4735.0 4539.1 4588.9 4732.3 4787.6 4803.4 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#4] prototype.html Cycles:180 Average:4322.71 Median:4963.20 stddev:1254.28 (25.3%) stddev-sans-first:1220.79 16:31:58 INFO - PROCESS | 12803 | Values: 293.5 310.9 311.9 313.8 316.7 4960.7 4984.3 5030.5 5039.2 5043.3 4682.2 4728.8 4835.1 4888.9 4965.7 3890.1 3901.9 3911.9 3936.5 3940.4 3903.8 3922.3 3925.9 3933.1 3945.9 3622.4 3702.6 3816.8 3830.5 3895.0 3893.3 3904.3 3910.2 3916.4 3917.7 3449.6 3465.3 3469.6 3477.1 3489.7 4722.4 4766.0 4795.2 4823.4 4840.9 4459.5 4497.6 4498.1 4505.4 4507.1 5178.9 5193.8 5194.2 5195.3 5198.9 3513.9 3806.6 3814.4 3817.6 3818.4 5379.4 5437.6 5456.3 5477.9 5485.9 3730.0 3750.7 3753.9 3759.2 3759.6 3831.3 3839.4 3844.8 3847.9 3848.6 4137.9 4342.5 4345.0 4349.0 4363.1 5162.5 5162.6 5182.7 5185.6 5195.6 1323.4 1328.0 1342.2 1343.2 1344.0 838.7 870.7 898.1 905.6 906.8 4570.9 4574.2 4574.9 4586.6 4593.1 5319.8 5348.9 5351.1 5360.2 5371.3 4412.6 4588.9 4592.7 4621.8 4637.0 2809.3 2817.3 2835.0 2843.1 2861.6 5009.5 5016.8 5028.1 5029.9 5050.9 5233.6 5413.1 5431.1 5440.3 5441.1 4991.5 5016.9 5026.7 5034.9 5040.3 5278.7 5282.7 5285.2 5292.7 5294.3 5022.6 5159.9 5198.5 5201.0 5207.7 5004.8 5013.5 5015.8 5021.4 5021.9 3706.2 3736.1 3742.3 3750.9 3755.2 5200.8 5305.1 5436.6 5449.8 5485.9 5274.8 5295.8 5307.8 5311.9 5313.6 5113.6 5229.1 5280.3 5282.7 5310.9 4920.0 5010.5 5177.2 5182.3 5215.4 5290.3 5311.9 5313.1 5315.6 5330.6 5189.3 5193.2 5196.2 5203.5 5217.5 16:31:58 INFO - PROCESS | 12803 | 16:31:58 INFO - PROCESS | 12803 | [#5] yui.html Cycles:180 Average:12201.42 Median:564.41 stddev:14079.34 (2494.5%) stddev-sans-first:14092.03 16:31:58 INFO - PROCESS | 12803 | Values: 631.6 650.9 653.5 670.2 728.6 11288.2 11448.4 11608.9 11648.1 11655.5 11442.5 11629.7 12117.1 12144.5 12158.5 638.9 778.7 813.7 814.0 827.4 804.0 805.2 809.1 811.2 811.4 837.1 842.3 842.5 843.8 844.1 668.0 822.5 830.3 845.3 845.7 622.4 622.7 623.2 623.6 623.7 11068.7 11102.0 11105.5 11116.7 11116.7 12228.6 12692.7 12798.2 12904.9 13115.9 9373.8 9408.6 9506.3 9532.8 9595.5 3301.1 3307.5 3368.5 3416.8 3472.9 44327.3 44447.8 44453.3 44518.0 44793.3 9967.4 10040.0 10191.7 10583.9 10854.6 18270.6 18316.6 18319.1 18457.7 18959.1 6648.0 6696.9 6714.9 6746.4 6780.8 3680.6 3718.9 3742.6 3775.3 3840.1 2552.2 2641.0 2647.2 2653.8 2656.6 467.5 479.2 488.2 489.3 490.4 596.8 604.7 612.9 622.1 644.3 7315.9 8046.8 8062.4 8147.3 8288.3 516.9 535.5 593.3 598.1 600.7 3748.8 3752.4 3777.7 3806.9 3809.0 51398.0 51817.7 51870.7 51946.7 52199.7 44031.8 44464.8 44792.0 45407.0 45504.3 40913.6 41517.6 41596.0 41900.4 41962.4 7118.3 7289.9 7449.4 7538.7 7559.5 27406.2 27429.2 27440.2 28328.3 28352.7 33826.0 35235.6 38006.0 38428.0 38911.0 9483.6 9727.7 9742.8 9895.4 9909.4 10233.9 10413.7 10510.1 10971.1 11033.6 8949.2 8976.2 9000.4 9367.3 9443.5 8766.8 8791.3 9043.5 9088.5 9181.0 9135.2 9148.1 9258.1 9297.6 9515.1 8100.5 8212.5 8319.6 8533.5 8799.9 7882.8 8291.6 8351.6 8408.8 8477.7 16:31:58 INFO - PROCESS | 12803 | -------- Summary: end -------- 16:31:58 INFO - PROCESS | 12803 | 16:31:59 INFO - TEST-INFO | 12803: exit 0 16:31:59 INFO - TEST-OK | dromaeo_css | took 1120371ms 16:31:59 INFO - TEST-START | kraken 16:31:59 INFO - operating with platform_type : linux_ 16:31:59 INFO - Initialising browser for kraken test... 16:31:59 INFO - TEST-INFO | started process 12981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpojLl9H/profile http://localhost:36749/getInfo.html) 16:32:04 INFO - PROCESS | 12981 | __metrics Screen width/height:1600/1200 16:32:04 INFO - PROCESS | 12981 | colorDepth:24 16:32:04 INFO - PROCESS | 12981 | Browser inner width/height: 1024/697 16:32:04 INFO - PROCESS | 12981 | __metrics 16:32:06 ERROR - PROCESS | 12981 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:32:06 INFO - TEST-INFO | 12981: exit 0 16:32:06 INFO - Browser initialized. 16:32:06 INFO - Running cycle 1/1 for kraken test... 16:32:06 INFO - TEST-INFO | started process 13063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpojLl9H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:18 INFO - PROCESS | 13063 | RSS: Main: 155086848 16:32:18 INFO - PROCESS | 13063 | 16:32:21 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36749/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:22 INFO - PROCESS | 13063 | RSS: Main: 209747968 16:32:22 INFO - PROCESS | 13063 | 16:32:25 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36749/tests/kraken/driver.html?testName=audio-dft) 16:32:25 INFO - PROCESS | 13063 | RSS: Main: 195014656 16:32:25 INFO - PROCESS | 13063 | 16:32:29 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36749/tests/kraken/driver.html?testName=audio-fft) 16:32:29 INFO - PROCESS | 13063 | RSS: Main: 266506240 16:32:29 INFO - PROCESS | 13063 | 16:32:31 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36749/tests/kraken/driver.html?testName=audio-oscillator) 16:32:32 INFO - PROCESS | 13063 | RSS: Main: 174178304 16:32:32 INFO - PROCESS | 13063 | 16:32:34 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36749/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:32:35 INFO - PROCESS | 13063 | RSS: Main: 178360320 16:32:35 INFO - PROCESS | 13063 | 16:32:38 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36749/tests/kraken/driver.html?testName=imaging-darkroom) 16:32:39 INFO - PROCESS | 13063 | RSS: Main: 213598208 16:32:39 INFO - PROCESS | 13063 | 16:32:42 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36749/tests/kraken/driver.html?testName=imaging-desaturate) 16:32:43 INFO - PROCESS | 13063 | RSS: Main: 215429120 16:32:43 INFO - PROCESS | 13063 | 16:32:46 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36749/tests/kraken/driver.html?testName=json-parse-financial) 16:32:46 INFO - PROCESS | 13063 | RSS: Main: 215687168 16:32:46 INFO - PROCESS | 13063 | 16:32:48 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36749/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:32:48 INFO - PROCESS | 13063 | RSS: Main: 200138752 16:32:48 INFO - PROCESS | 13063 | 16:32:50 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:32:51 INFO - PROCESS | 13063 | RSS: Main: 206053376 16:32:51 INFO - PROCESS | 13063 | 16:32:53 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:32:54 INFO - PROCESS | 13063 | RSS: Main: 219152384 16:32:54 INFO - PROCESS | 13063 | 16:32:57 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:32:57 INFO - PROCESS | 13063 | RSS: Main: 222093312 16:32:57 INFO - PROCESS | 13063 | 16:33:00 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:33:01 INFO - PROCESS | 13063 | RSS: Main: 217903104 16:33:01 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | Cycle 1(1): loaded http://localhost:36749/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36749/tests/kraken/driver.html?testName=ai-astar) 16:33:03 INFO - PROCESS | 13063 | RSS: Main: 281686016 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | __start_tp_report 16:33:03 INFO - PROCESS | 13063 | _x_x_mozilla_page_load 16:33:03 INFO - PROCESS | 13063 | _x_x_mozilla_page_load_details 16:33:03 INFO - PROCESS | 13063 | |i|pagename|runs| 16:33:03 INFO - PROCESS | 13063 | |0;ai-astar;112;116;107;114;112;113;102;113;116;113 16:33:03 INFO - PROCESS | 13063 | |1;audio-beat-detection;163;133;162;192;137;178;166;136;202;131 16:33:03 INFO - PROCESS | 13063 | |2;audio-dft;161;161;163;163;162;165;161;162;162;161 16:33:03 INFO - PROCESS | 13063 | |3;audio-fft;77;74;108;110;76;73;78;119;103;73 16:33:03 INFO - PROCESS | 13063 | |4;audio-oscillator;94;96;148;95;94;94;148;94;94;141 16:33:03 INFO - PROCESS | 13063 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 16:33:03 INFO - PROCESS | 13063 | |6;imaging-darkroom;146;145;146;146;145;146;146;146;146;145 16:33:03 INFO - PROCESS | 13063 | |7;imaging-desaturate;95;94;96;94;95;95;95;96;95;94 16:33:03 INFO - PROCESS | 13063 | |8;json-parse-financial;66;66;65;65;65;66;68;83;64;63 16:33:03 INFO - PROCESS | 13063 | |9;json-stringify-tinderbox;64;64;65;64;65;67;65;65;66;65 16:33:03 INFO - PROCESS | 13063 | |10;stanford-crypto-aes;78;79;78;87;87;78;79;79;80;80 16:33:03 INFO - PROCESS | 13063 | |11;stanford-crypto-ccm;133;132;130;131;133;131;132;132;221;140 16:33:03 INFO - PROCESS | 13063 | |12;stanford-crypto-pbkdf2;176;173;175;184;177;173;178;178;172;173 16:33:03 INFO - PROCESS | 13063 | |13;stanford-crypto-sha256-iterative;74;74;73;74;72;73;73;73;74;74 16:33:03 INFO - PROCESS | 13063 | __end_tp_report 16:33:03 INFO - PROCESS | 13063 | __start_cc_report 16:33:03 INFO - PROCESS | 13063 | _x_x_mozilla_cycle_collect,1891 16:33:03 INFO - PROCESS | 13063 | __end_cc_report 16:33:03 INFO - PROCESS | 13063 | __startTimestamp1461022383335__endTimestamp 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | ------- Summary: start ------- 16:33:03 INFO - PROCESS | 13063 | Number of tests: 14 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:4.26 (3.8%) stddev-sans-first:4.52 16:33:03 INFO - PROCESS | 13063 | Values: 112.0 116.0 107.0 114.0 112.0 113.0 102.0 113.0 116.0 113.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#1] audio-beat-detection Cycles:10 Average:160.00 Median:164.50 stddev:25.46 (15.5%) stddev-sans-first:26.99 16:33:03 INFO - PROCESS | 13063 | Values: 163.0 133.0 162.0 192.0 137.0 178.0 166.0 136.0 202.0 131.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.30 16:33:03 INFO - PROCESS | 13063 | Values: 161.0 161.0 163.0 163.0 162.0 165.0 161.0 162.0 162.0 161.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#3] audio-fft Cycles:10 Average:89.10 Median:73.50 stddev:18.47 (25.1%) stddev-sans-first:19.06 16:33:03 INFO - PROCESS | 13063 | Values: 77.0 74.0 108.0 110.0 76.0 73.0 78.0 119.0 103.0 73.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:94.00 stddev:24.83 (26.4%) stddev-sans-first:25.67 16:33:03 INFO - PROCESS | 13063 | Values: 94.0 96.0 148.0 95.0 94.0 94.0 148.0 94.0 94.0 141.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:33:03 INFO - PROCESS | 13063 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:33:03 INFO - PROCESS | 13063 | Values: 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 16:33:03 INFO - PROCESS | 13063 | Values: 95.0 94.0 96.0 94.0 95.0 95.0 95.0 96.0 95.0 94.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:66.00 stddev:5.74 (8.7%) stddev-sans-first:6.08 16:33:03 INFO - PROCESS | 13063 | Values: 66.0 66.0 65.0 65.0 65.0 66.0 68.0 83.0 64.0 63.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.94 (1.5%) stddev-sans-first:0.93 16:33:03 INFO - PROCESS | 13063 | Values: 64.0 64.0 65.0 64.0 65.0 67.0 65.0 65.0 66.0 65.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:79.50 stddev:3.50 (4.4%) stddev-sans-first:3.60 16:33:03 INFO - PROCESS | 13063 | Values: 78.0 79.0 78.0 87.0 87.0 78.0 79.0 79.0 80.0 80.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#11] stanford-crypto-ccm Cycles:10 Average:141.50 Median:132.50 stddev:28.07 (21.2%) stddev-sans-first:29.60 16:33:03 INFO - PROCESS | 13063 | Values: 133.0 132.0 130.0 131.0 133.0 131.0 132.0 132.0 221.0 140.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:176.50 stddev:3.60 (2.0%) stddev-sans-first:3.82 16:33:03 INFO - PROCESS | 13063 | Values: 176.0 173.0 175.0 184.0 177.0 173.0 178.0 178.0 172.0 173.0 16:33:03 INFO - PROCESS | 13063 | 16:33:03 INFO - PROCESS | 13063 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:74.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 16:33:03 INFO - PROCESS | 13063 | Values: 74.0 74.0 73.0 74.0 72.0 73.0 73.0 73.0 74.0 74.0 16:33:03 INFO - PROCESS | 13063 | -------- Summary: end -------- 16:33:03 INFO - PROCESS | 13063 | 16:33:04 INFO - TEST-INFO | 13063: exit 0 16:33:04 INFO - TEST-OK | kraken | took 65278ms 16:33:04 INFO - SUITE-END | took 1185s 16:33:04 INFO - Completed test suite (00:19:45) 16:33:04 INFO - javascript benchmark 16:33:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2591.8833652485637, 2683.2621029216975, 2683.510651588369, 2694.3129501977005, 2714.5113879501682, 4461.107778444308, 4532.116237333721, 4603.2404653232215, 4610.861674149776, 4636.953630463699, 4562.680612357136, 4584.816607335711, 4642.257238841786, 4682.0, 4712.0, 4242.512111107224, 4252.489701235856, 4261.424707664457, 4293.098449325646, 4313.633341166004, 4323.0, 4339.41417908583, 4385.057212699269, 4388.42950416447, 4397.318415645564, 4153.937690934638, 4231.217224813388, 4241.491021077491, 4290.978545107255, 4350.782460876943, 4197.559256278078, 4215.747055176699, 4217.683673724483, 4222.071144348239, 4227.365895115727, 4176.561461046607, 4205.137946720914, 4206.47419072616, 4209.97895010526, 4268.0823622921225, 4492.281235002384, 4503.572160644733, 4523.751193684346, 4551.0215303709865, 4573.062522182944, 4710.646701497401, 4959.256111583255, 4960.826371077031, 4962.60299176065, 4997.05056039355, 4950.777215025332, 4951.455339912643, 4992.750362481897, 5004.099262132815, 5038.647294689373, 4569.725816450957, 4613.077384523109, 4622.329762184464, 4623.260278355447, 4659.860204193879, 4965.925511117336, 5034.924476132861, 5043.394792624908, 5053.646244762905, 5062.7468626568525, 4880.8779780505065, 4897.14299997499, 4905.3132561441425, 4916.655834091579, 4955.603551715854, 4968.080905032581, 4970.8757281068265, 4990.8752281193265, 4992.625553083533, 5041.747912604355, 4762.618990480753, 4865.878353041202, 4910.0, 4955.157623204064, 4973.825916092919, 4936.210206367035, 4952.182889823139, 4953.950460495349, 4955.578775804025, 4960.677555958851, 4189.575544314947, 4190.413342132104, 4197.391378250137, 4209.17921005401, 4234.894127646833, 3512.5609298837644, 3648.9817550912076, 3661.3409586274192, 3671.6328367163605, 3758.548974123068, 4340.934885976713, 4358.5205627380965, 4361.1495758327455, 4365.454318210223, 4409.030013397048, 5011.473795251446, 5046.34397528319, 5048.091343558195, 5051.191809310492, 5098.770555325019, 4198.6641068714425, 4260.936085958713, 4326.978365108154, 4330.0, 4352.542982986804, 4404.955950440519, 4423.977880110579, 4428.402165707653, 4452.4211852458975, 4468.329750537446, 4701.318308845267, 4702.365180700698, 4704.929426058543, 4705.7411842349, 4753.881152971203, 4882.829100981448, 4950.702957822542, 4956.752162391794, 5027.4218464877, 5047.444781074006, 4734.928976065431, 4767.284907263938, 4775.904481910412, 4783.736894470838, 4823.493533179106, 4935.0, 4936.062148191832, 4937.925931111108, 4942.4810394909455, 4962.454130045764, 4780.378550788375, 4963.255511673278, 5005.174146265826, 5018.272350509083, 5051.823186188466, 4807.966287248259, 4812.903741925072, 4812.951870481251, 4845.5881250092725, 4858.732769697701, 4783.282507623884, 4784.378030855966, 4787.593054590398, 4793.448753393404, 4843.128236917248, 4615.0, 4733.36099626546, 4754.548317909793, 4806.086843499724, 4837.830675926326, 4771.045790841776, 4783.593394561502, 4791.784369703441, 4793.352897358952, 4834.347363105936, 4684.6486513510945, 4694.061187762393, 4697.459792123949, 4733.242681170997, 4749.430068391815], "value": 4555.526697600405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.2477354665386, 599.7630935780367, 605.3855336833116, 614.6957256158203, 618.6504624886938, 26622.733772662268, 34180.487292690595, 35230.8238458808, 35262.0, 35278.29443411133, 36674.44988325177, 37537.81231093841, 37575.436368454495, 37651.0, 37655.811720941354, 7652.1582625911105, 7781.182975787546, 7832.255935686101, 7837.333826624744, 7854.136045035041, 7772.80567985802, 7839.843203135933, 7841.764747057598, 7874.212578742108, 7898.684052637887, 7806.492577982442, 7863.488873223251, 7880.842383152333, 7901.762947111596, 7970.123286438487, 7844.843103137905, 7853.41099417546, 7857.882131768057, 7860.213978602123, 7929.484583502082, 7709.922900770976, 7818.569978651172, 7821.530708157528, 7822.765317040498, 7830.0, 37131.443028354595, 37502.624973750186, 37510.43734343987, 37563.060923476856, 37594.0, 2983.4778913690016, 3169.762267829922, 3197.8560964756643, 3452.5684289463816, 3709.2025214578725, 28389.85805070982, 28428.71571284302, 28779.424411511864, 29228.41543169146, 29239.0, 3218.7103160715415, 3245.383377158332, 3269.75476839238, 3295.423300922353, 3395.7453191010773, 199128.0, 200236.0, 200838.0, 201156.99999999854, 202223.0, 50858.745706271235, 52059.73970130201, 52532.47467525352, 52534.0, 52536.73731631394, 54613.72693136509, 59310.70344648249, 61211.08183377253, 61480.69259653763, 61586.38413615896, 14530.782038269434, 14688.0, 14777.0, 14779.0, 14788.630284242765, 11105.722356941142, 11262.267952583055, 11283.887161128448, 11327.320360778214, 11376.829347559793, 17908.91045544764, 17976.37082702099, 18047.909760451115, 18100.366487172887, 18607.44177674672, 6676.123592699527, 6809.931900681029, 7046.189688185822, 7051.294870512907, 7134.92865071353, 859.6819176904586, 923.9953800230955, 934.7289286107089, 1008.7528555503802, 1062.654637242899, 26249.475010499682, 27881.0, 28207.858960705064, 28300.292492687437, 28370.43259134847, 992.6178421307849, 1004.829179039565, 1007.3150257824603, 1025.6307729217476, 1090.2150451674781, 14480.710385792225, 14845.851541484446, 15057.924710376597, 15150.848491515164, 15326.923365383102, 322500.0, 323199.0, 328402.0, 328609.0, 329774.0, 47562.76218618885, 48512.27231591529, 48559.757201214474, 48605.02789944181, 48706.75646621744, 1124.4153040418937, 1137.3119262846024, 1160.1762748448527, 1228.5761412312916, 1236.097648716442, 26409.735902641496, 27246.455070898868, 27262.318442039108, 27970.72029279681, 27988.30029249285, 38272.0, 38910.0, 38966.4155037669, 38979.80510097545, 39073.413898790976, 212658.0, 215634.0, 215690.0, 215954.0, 216255.0, 25210.117645882307, 37766.24467510689, 39159.6084039156, 39291.21417571575, 39698.0, 37234.25531489409, 38512.22975540417, 39012.609873900896, 39291.0, 39461.40807887827, 33380.66619333873, 33395.332093358484, 34007.0, 34397.48403773995, 34784.65215347814, 33130.834345828116, 33174.17064573405, 33211.50182747309, 33467.0, 33490.0, 33565.49651755275, 34195.0, 34327.485087724206, 34530.65469345274, 34640.65359346374, 29122.56316155302, 30386.39227215399, 30505.84747076339, 30560.0, 31091.222719432197, 26020.8698956504, 27214.183574492796, 27250.318742030813, 27456.862715686293, 27490.450190996467], "value": 17137.33042120774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.83275442428123, 383.29856362855975, 384.3984164782116, 388.6405075305342, 394.2332163941136, 15712.428757124297, 17237.655246895054, 17628.535154102236, 17884.0, 17888.373906913228, 17068.82931170688, 17334.739978900325, 17690.203940822663, 17932.0, 17939.282428702867, 4905.75471226439, 5046.84859454217, 5075.5178258065425, 5096.464871188509, 5096.541311281984, 3640.5631324240994, 3785.6403641654138, 5322.88219473909, 5959.0, 5967.701614919259, 5715.71421428929, 5926.822195334125, 5931.911021334662, 5958.493528050121, 5976.820695379124, 4995.525425084611, 5293.523582877559, 5298.814541491068, 5307.681539107666, 5948.167256584082, 3331.750118741104, 5025.447200807908, 5133.666311689728, 5362.329708786402, 5374.381946076212, 12198.939005304917, 12287.56993505232, 12374.876251237463, 13744.587662370144, 20707.0, 1790.4897104325335, 1811.8641101917408, 1929.4404622659413, 1991.412533302679, 2209.103217585211, 8831.425957312755, 8907.688230911886, 9514.0, 9711.36876103058, 9741.853872191923, 1612.2986500872157, 1617.8948368356018, 1730.5327561558436, 1735.9826401736073, 1770.371518111065, 62687.68656156736, 65978.0, 67147.66426167887, 67812.32187678159, 69407.65296173487, 10150.593976241016, 10175.7456063599, 10312.329698569569, 10342.0, 10355.534000969823, 14175.929120354333, 14237.857621423653, 14272.928635356757, 14305.141691498544, 14319.498817541544, 7190.416740978576, 7413.666385012687, 7528.9835872157655, 7558.470907036454, 7740.303372696371, 6841.486888483284, 6966.12923384577, 6967.895481567781, 7115.786526404217, 7240.855182896313, 4299.0, 4418.182636212311, 4469.955300447019, 4500.819967201341, 4536.296873984472, 1798.0781230500436, 1877.1433128638735, 1887.7923428422862, 1902.0869982408522, 1912.1682068300333, 841.066416277931, 858.8754630578532, 862.6980556805069, 910.5902343945119, 954.9331546791658, 16072.75890861638, 16432.0, 16673.24970376336, 16873.578160546083, 17177.31290748356, 827.7640872351351, 871.0, 898.5821592959305, 932.128459890005, 933.1461712533091, 9249.583768730423, 9318.72043838686, 10074.899251007397, 10136.2904596679, 10158.288919775692, 409963.0, 413017.0, 419026.0, 419647.0, 419707.0, 53828.0, 78245.21754782379, 78546.0, 82803.0, 85498.5725071383, 878.1131057631837, 947.6114792934864, 958.6357184269933, 980.8724865767405, 994.7612572982576, 9516.524173791422, 9698.806023879342, 9774.31579789408, 10094.444805535766, 10283.640072597424, 32778.50832237566, 32810.835945820116, 33191.17022074371, 33422.33155336928, 35501.467477988364, 48206.0, 49687.75156124196, 49720.502794973036, 49836.75081624569, 50362.0, 6639.601623902388, 8870.42342247752, 10678.359298442118, 11230.66308010761, 11714.765704685688, 10489.53146559467, 11318.830217546578, 11960.222585531912, 12233.266004039786, 12466.376681166086, 9227.569726692373, 10222.59109635636, 10962.177836662442, 11173.776524469302, 11440.313581185155, 10694.111351158528, 10828.404437755999, 11049.39228342449, 11136.443177841238, 11478.311301321948, 11242.10063194926, 11286.379249141377, 11293.209475336977, 11477.713057173638, 11604.6518604442, 11120.833187502032, 11151.72120696989, 11226.326420414802, 11264.94367528157, 11299.604513841976, 11119.499622516845, 11202.32786032808, 11291.378974156172, 11332.660020199408, 11479.655610331703], "value": 8262.959069777842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1263.684078980255, 1430.8426073131955, 1460.9050411723238, 1589.7376932806087, 1789.016041177353, 6779.898301525475, 7573.621318934059, 7807.180246074166, 7821.687132514707, 7821.726239581609, 7691.807704807383, 7803.5708036057995, 7910.644021019054, 7919.247671471216, 7921.762347129596, 2250.1112060736, 2283.2579411691186, 2284.0407029047838, 2285.5543169082034, 2286.7827556382117, 2236.4297104238394, 2260.547890421922, 2297.2993237062688, 2297.310806757966, 2298.5517824024237, 2207.2385027165683, 2225.3991422316044, 2231.977680223193, 2254.3462395905167, 2257.063318722728, 2222.744384395799, 2234.0, 2236.910523579055, 2239.9216027439043, 2242.708447901762, 2199.549092436047, 2205.38249290199, 2214.3467677035314, 2220.378294077661, 2221.766714494971, 7676.347510461617, 7706.845863082707, 7852.3325517331095, 7858.292753652198, 7953.642086106139, 4512.909741805145, 4652.069586082797, 4671.205894997858, 4671.88320291992, 4674.836380726692, 2931.1646180838356, 2950.100219433072, 2976.657684366304, 2987.8944790427463, 2997.9400411991637, 912.0833562269943, 925.7500474871814, 929.7954450021052, 946.6402766948585, 956.0, 10221.284510084297, 10299.691009269734, 10324.587016519405, 10327.431991240479, 10414.0, 3980.3631418972827, 4077.836886524565, 4186.6859985501205, 4236.110416812504, 4237.597428244312, 8175.959120204361, 8225.0, 8252.669893204325, 8260.586970651444, 8266.586670666442, 4581.060882519142, 4592.563706447882, 4593.035462552902, 4602.723836569749, 4617.676762626584, 3722.758020728644, 4030.5767894371256, 4114.033202197481, 4151.8962025949595, 4223.303154979455, 3498.510208570802, 3529.5940966788635, 3567.3222087803233, 3607.801570913625, 3649.817509124533, 984.7931934293736, 1103.227740581596, 1107.6510899066768, 1107.7396811749231, 1108.0692218536308, 725.6734469488752, 763.7097902796902, 770.7379490973096, 836.0928392693874, 874.1608056265928, 5185.429602743695, 5234.3718753749035, 5459.836204913859, 5477.096279113902, 5496.367917689437, 534.8582625604218, 541.1476923844959, 544.2625242796033, 552.9889402211933, 662.2715013485224, 3753.2868754936476, 3780.432935059763, 3850.7111966602056, 3873.302805495038, 3884.941725874114, 9388.62445502186, 9779.0, 9823.901760982299, 9832.0, 9832.655857045112, 9794.167495762798, 9796.412215267106, 9807.901920980841, 9808.558614862348, 9812.067853553901, 7092.0, 7308.853822923512, 7331.596762178025, 7344.742933997284, 7369.1525474570035, 5290.206469029677, 5320.255164276927, 5416.295881535375, 5424.83725488236, 5650.180723795109, 8614.870776938225, 8681.91318086811, 8788.03331633507, 8794.516301603473, 8824.249938755278, 8518.083431227924, 8782.077881822317, 8933.240674542476, 9028.864567031631, 9034.367594268339, 6381.361863813581, 7192.964035179791, 7259.782206533812, 7408.888866667112, 7436.73971410998, 7430.3684186842565, 7466.925330746623, 7474.738384156529, 7499.287567681062, 7524.097108347033, 6513.674316284072, 6646.302138275411, 6684.532082754355, 6763.019362192356, 6818.863622727418, 6592.80221593353, 6608.4052435281055, 6617.867642647024, 6718.56329338611, 6798.082258894986, 6359.936400636119, 6467.967660161858, 6511.0, 6534.150560427114, 6551.344865513411, 4476.2837945928495, 4553.567411095941, 4566.178087944202, 4598.678092533624, 4734.97632511849, 4539.092181563767, 4588.885277867947, 4732.313814496947, 4787.593054590398, 4803.447603525639], "value": 4102.481790333085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4732155780374, 310.9253779093017, 311.91734190439536, 313.7725149266781, 316.6943899137332, 4960.702357858531, 4984.311609409038, 5030.547250747432, 5039.168537191368, 5043.31915191449, 4682.227432473638, 4728.763561821912, 4835.105505481489, 4888.853334399965, 4965.70205787653, 3890.144168282973, 3901.8634347797833, 3911.921761564774, 3936.5079365079364, 3940.4483372327895, 3903.8048097595147, 3922.313595120857, 3925.941110883339, 3933.075727204119, 3945.8618948336816, 3622.4385220290765, 3702.5556933167923, 3816.847326106966, 3830.4828848105567, 3895.0, 3893.279743247508, 3904.297226499229, 3910.2179564087155, 3916.3929590913453, 3917.7061720371084, 3449.58604967403, 3465.324261768956, 3469.6356882527225, 3477.078574177845, 3489.668481494254, 4722.362481065081, 4765.95829413144, 4795.184818580849, 4823.421189457287, 4840.927386089212, 4459.487158976727, 4497.572730590588, 4498.077894031716, 4505.414296141481, 4507.076049409863, 5178.896422071537, 5193.844184674465, 5194.220866869962, 5195.3246078009615, 5198.896022079537, 3513.8945831624837, 3806.5812760596314, 3814.389697648363, 3817.6373244541724, 3818.4463252828464, 5379.38137114233, 5437.619366644297, 5456.290682211326, 5477.904983684245, 5485.862853428656, 3730.0, 3750.662440380379, 3753.887383378503, 3759.191773768653, 3759.6240375962184, 3831.3103641344824, 3839.404892241707, 3844.826982785781, 3847.942280865789, 3848.615138486129, 4137.8965525862095, 4342.522322544518, 4345.022369966732, 4348.978255108705, 4363.149185908781, 5162.483751624807, 5162.6128040397125, 5182.740862956837, 5185.559227465707, 5195.636305458582, 1323.4309247023873, 1327.9734405312033, 1342.174562643967, 1343.2007955266602, 1343.9932800335935, 838.7106448275457, 870.6822009966317, 898.1063841477641, 905.5698543191932, 906.7551761024619, 4570.862874113782, 4574.153781550423, 4574.931376029363, 4586.564276393738, 4593.127305811885, 5319.840404787863, 5348.893022139536, 5351.09031464652, 5360.16917377807, 5371.328583927009, 4412.624926881187, 4588.862334129982, 4592.655550833634, 4621.768911554476, 4636.976815115903, 2809.339805145789, 2817.2534278416238, 2834.9858250708617, 2843.1470558832266, 2861.570764385359, 5009.499050095034, 5016.799328026911, 5028.069807085701, 5029.899402011939, 5050.903874891952, 5233.555147812479, 5413.079776438015, 5431.076716958127, 5440.3471583409455, 5441.143399715181, 4991.450940396553, 5016.949830501649, 5026.698398096126, 5034.924476132861, 5040.2691609716385, 5278.709670968134, 5282.735863206901, 5285.207218917193, 5292.708901010481, 5294.311739473844, 5022.648414610942, 5159.896802064013, 5198.532132108128, 5201.037808005531, 5207.661502002509, 5004.824831130893, 5013.473585273639, 5015.824446144367, 5021.447640759587, 5021.924671129863, 3706.2402207547925, 3736.1220113272816, 3742.326381251401, 3750.94373584391, 3755.1926335837925, 5200.765965531485, 5305.098133317269, 5436.565074794086, 5449.773800894767, 5485.917711234255, 5274.841754747364, 5295.76169072385, 5307.7611507483025, 5311.946880531145, 5313.601479888946, 5113.616478764033, 5229.084910140634, 5280.313559237275, 5282.709450980233, 5310.9468905312, 4920.039368308546, 5010.549050585465, 5177.171652535576, 5182.274481572658, 5215.426303106731, 5290.259363689163, 5311.920321195108, 5313.096773548429, 5315.6279060465395, 5330.600204984564, 5189.325387699575, 5193.169092945111, 5196.194589838581, 5203.453637368022, 5217.478252174752], "value": 3924.575557652785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.6304961597466, 650.9056186852904, 653.4674240493997, 670.1991120610872, 728.5519405565577, 11288.153388495864, 11448.427578621067, 11608.941955290233, 11648.068154547636, 11655.533778648854, 11442.485088171032, 11629.651110466699, 12117.091218158652, 12144.453499592539, 12158.513659453632, 638.907358433027, 778.7196609220705, 813.6663967773229, 814.0150417994234, 827.4001349021971, 803.9598020099, 805.1586092533288, 809.0816922792643, 811.2171754257141, 811.4157806379397, 837.1042984007115, 842.3261390887296, 842.4945032980224, 843.8227972125861, 844.1010324004945, 668.0280192320187, 822.5352675738249, 830.3191383065858, 845.344857735254, 845.6997765793117, 622.4491325177205, 622.707327556048, 623.1929651101789, 623.6258245052977, 623.7037407231529, 11068.723281917935, 11102.0, 11105.500252488657, 11116.666500005012, 11116.722081948015, 12228.571999980048, 12692.739205115284, 12798.232106073572, 12904.87095129046, 13115.934420327838, 9373.812523749486, 9408.623655053789, 9506.334556581041, 9532.761680958032, 9595.472249026301, 3301.0591981285456, 3307.4873394623874, 3368.4947257911263, 3416.829158542088, 3472.8958131256104, 44327.335089973676, 44447.77776111131, 44453.333200002024, 44518.0, 44793.32810007853, 9967.352122112039, 10040.0, 10191.694249172537, 10583.94708026455, 10854.565817367375, 18270.63458730818, 18316.63366732658, 18319.084045797656, 18457.723134153, 18959.146838392306, 6648.03603477493, 6696.8995465068065, 6714.932850671528, 6746.359095885883, 6780.796576102724, 3680.576733675608, 3718.88843334695, 3742.5883152853166, 3775.339315619756, 3840.135969406917, 2552.2470871093074, 2640.9735902641114, 2647.179374393944, 2653.840769553833, 2656.6147908553153, 467.47409164689725, 479.1519011349891, 488.2334734466924, 489.2709862305211, 490.448245723561, 596.7791916990741, 604.703695189354, 612.9049997250434, 622.1197006236216, 644.2751904107879, 7315.926840731631, 8046.839063218703, 8062.39532035088, 8147.266745992889, 8288.295494882881, 516.9121249387613, 535.4939582094961, 593.3325009364465, 598.141666708275, 600.6576251536583, 3748.8125593720206, 3752.442219449227, 3777.697784177259, 3806.873245036415, 3808.961910380916, 51398.0, 51817.7409112952, 51870.74064629653, 51946.740266298424, 52199.73900130475, 44031.779841100586, 44464.777676111415, 44792.0, 45407.0, 45504.317435238496, 40913.59086409098, 41517.58482415198, 41596.0, 41900.37149442761, 41962.37056444156, 7118.323759242864, 7289.854202916018, 7449.404047676281, 7538.736144234926, 7559.470837041355, 27406.177814665592, 27429.17712468629, 27440.17679469619, 28328.291792705346, 28352.716472835833, 33826.0, 35235.64764352324, 38006.0, 38428.0, 38911.0, 9483.620655173716, 9727.65953191635, 9742.75643108928, 9895.356801807859, 9909.405435673883, 10233.902050079567, 10413.687589372332, 10510.106640935606, 10971.067459266053, 11033.613823516138, 8949.239314658327, 8976.237019853386, 9000.36997410175, 9367.250619950524, 9443.480608566595, 8766.824663506823, 8791.34064945119, 9043.502607356659, 9088.545572721205, 9181.0, 9135.177833994972, 9148.085191480799, 9258.074192580689, 9297.628094876129, 9515.096065873731, 8100.513969161869, 8212.507249565046, 8319.625616847135, 8533.53065581399, 8799.912000880166, 7882.763517094496, 8291.626876790438, 8351.624176911931, 8408.831823363376, 8477.703280385156], "value": 5661.001473361463, "unit": "score"}], "name": "dromaeo_css", "value": 6235.908934998356, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 107.0, 114.0, 112.0, 113.0, 102.0, 113.0, 116.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 133.0, 162.0, 192.0, 137.0, 178.0, 166.0, 136.0, 202.0, 131.0], "value": 160.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 163.0, 163.0, 162.0, 165.0, 161.0, 162.0, 162.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 108.0, 110.0, 76.0, 73.0, 78.0, 119.0, 103.0, 73.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 148.0, 95.0, 94.0, 94.0, 148.0, 94.0, 94.0, 141.0], "value": 109.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 68.0, 83.0, 64.0, 63.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 67.0, 65.0, 65.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 87.0, 87.0, 78.0, 79.0, 79.0, 80.0, 80.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 130.0, 131.0, 133.0, 131.0, 132.0, 132.0, 221.0, 140.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 173.0, 175.0, 184.0, 177.0, 173.0, 178.0, 178.0, 172.0, 173.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 72.0, 73.0, 73.0, 73.0, 74.0, 74.0], "value": 73.4, "unit": "score"}], "name": "kraken", "value": 1593.7, "alertThreshold": 2.0}]} 16:33:05 INFO - Return code: 0 16:33:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:33:05 ERROR - # TBPL SUCCESS # 16:33:05 INFO - Running post-action listener: _resource_record_post_action 16:33:05 INFO - Running post-run listener: _resource_record_post_run 16:33:06 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 523538432; Read time: 12; Write time: 1455688 16:33:06 INFO - install - Wall time: 17s; CPU: 17.0%; Read bytes: 0; Write bytes: 233984000; Read time: 0; Write time: 701580 16:33:06 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289554432; Read time: 12; Write time: 754108 16:33:06 INFO - Running post-run listener: _upload_blobber_files 16:33:06 INFO - Blob upload gear active. 16:33:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:33:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:33:06 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'] 16:33:06 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 16:33:06 INFO - (blobuploader) - INFO - Open directory for files ... 16:33:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:33:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:33:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:07 INFO - (blobuploader) - INFO - Done attempting. 16:33:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:33:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:33:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:08 INFO - (blobuploader) - INFO - Done attempting. 16:33:08 INFO - (blobuploader) - INFO - Iteration through files over. 16:33:08 INFO - Return code: 0 16:33:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:33:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:33:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a995d74057888751dba12191b635dbdd9c0a7bd860543e5f435ac312c35755aecac7757d8b73dcbc1d6f679008a53932284348705bd6da8088779786cc83ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/680fbb203962ddf4ed85f537d22a1cfa7f1d51960ce90b5fbe3b0ce976d74295adaa1bde9bb6e722c3912dff4529bc8a7975a188361093fb5781a565d24d5e7f"} 16:33:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:33:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:33:08 INFO - Contents: 16:33:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a995d74057888751dba12191b635dbdd9c0a7bd860543e5f435ac312c35755aecac7757d8b73dcbc1d6f679008a53932284348705bd6da8088779786cc83ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/680fbb203962ddf4ed85f537d22a1cfa7f1d51960ce90b5fbe3b0ce976d74295adaa1bde9bb6e722c3912dff4529bc8a7975a188361093fb5781a565d24d5e7f"} 16:33:08 INFO - Running post-run listener: copy_logs_to_upload_dir 16:33:08 INFO - Copying logs to upload dir... 16:33:08 INFO - mkdir: /builds/slave/test/build/upload/logs 16:33:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.912824 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-04-18 16:33:08.430087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 16:33:08.433448) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a995d74057888751dba12191b635dbdd9c0a7bd860543e5f435ac312c35755aecac7757d8b73dcbc1d6f679008a53932284348705bd6da8088779786cc83ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/680fbb203962ddf4ed85f537d22a1cfa7f1d51960ce90b5fbe3b0ce976d74295adaa1bde9bb6e722c3912dff4529bc8a7975a188361093fb5781a565d24d5e7f"} build_url:https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011426 build_url: 'https://queue.taskcluster.net/v1/task/VooVRGqdTsm7Tw_FNquKKw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5a995d74057888751dba12191b635dbdd9c0a7bd860543e5f435ac312c35755aecac7757d8b73dcbc1d6f679008a53932284348705bd6da8088779786cc83ed1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/680fbb203962ddf4ed85f537d22a1cfa7f1d51960ce90b5fbe3b0ce976d74295adaa1bde9bb6e722c3912dff4529bc8a7975a188361093fb5781a565d24d5e7f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 16:33:08.461110) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:33:08.461831) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1461017901.168679-193700292 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 16:33:08.542224) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 16:33:08.542609) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 16:33:08.543229) ========= ========= Total master_lag: 0.18 =========