builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-034 starttime: 1461634113.38 results: success (0) buildid: 20160425155612 builduid: 1efcd640d5b44eb1aaad13ac6ab462a5 revision: 80befee8ec527372547b547cc592e555d3b6c11a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.377254) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.377688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.377954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.397562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.397830) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 18:28:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 222M=0s 2016-04-25 18:28:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106015 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.517180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.517515) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015786 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.567820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:33.568220) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 80befee8ec527372547b547cc592e555d3b6c11a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 80befee8ec527372547b547cc592e555d3b6c11a --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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 18:28:34,086 truncating revision to first 12 chars 2016-04-25 18:28:34,086 Setting DEBUG logging. 2016-04-25 18:28:34,086 attempt 1/10 2016-04-25 18:28:34,086 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/80befee8ec52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 18:28:34,340 unpacking tar archive at: mozilla-aurora-80befee8ec52/testing/mozharness/ program finished with exit code 0 elapsedTime=0.562799 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:34.143461) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:34.143773) ========= script_repo_revision: 80befee8ec527372547b547cc592e555d3b6c11a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:34.144171) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:34.144431) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 18:28:34.152480) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-25 18:28:34.152834) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:28:34 INFO - MultiFileLogger online at 20160425 18:28:34 in /builds/slave/test 18:28:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:28:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:28:34 INFO - {'append_to_log': False, 18:28:34 INFO - 'base_work_dir': '/builds/slave/test', 18:28:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:28:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:28:34 INFO - 'branch': 'Mozilla-Aurora', 18:28:34 INFO - 'buildbot_json_path': 'buildprops.json', 18:28:34 INFO - 'config_files': ('talos/linux_config.py',), 18:28:34 INFO - 'default_actions': ('clobber', 18:28:34 INFO - 'read-buildbot-config', 18:28:34 INFO - 'download-and-extract', 18:28:34 INFO - 'populate-webroot', 18:28:34 INFO - 'create-virtualenv', 18:28:34 INFO - 'install', 18:28:34 INFO - 'run-tests'), 18:28:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:28:34 INFO - 'download_minidump_stackwalk': True, 18:28:34 INFO - 'download_symbols': 'ondemand', 18:28:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:28:34 INFO - 'tooltool.py': '/tools/tooltool.py', 18:28:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:28:34 INFO - '/tools/misc-python/virtualenv.py')}, 18:28:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:28:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:28:34 INFO - 'installer_path': 'installer.exe', 18:28:34 INFO - 'log_level': 'info', 18:28:34 INFO - 'log_name': 'talos', 18:28:34 INFO - 'log_to_console': True, 18:28:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:28:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:28:34 INFO - 'opt_config_files': (), 18:28:34 INFO - 'pip_index': False, 18:28:34 INFO - 'sps_profile': False, 18:28:34 INFO - 'sps_profile_interval': 0, 18:28:34 INFO - 'suite': 'dromaeojs-e10s', 18:28:34 INFO - 'system_bits': '32', 18:28:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:28:34 INFO - 'title': 'talos-linux64-ix-034', 18:28:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:28:34 INFO - 'use_talos_json': True, 18:28:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:28:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:28:34 INFO - 'work_dir': 'build'} 18:28:34 INFO - ##### 18:28:34 INFO - ##### Running clobber step. 18:28:34 INFO - ##### 18:28:34 INFO - Running pre-action listener: _resource_record_pre_action 18:28:34 INFO - Running main action method: clobber 18:28:34 INFO - rmtree: /builds/slave/test/build 18:28:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:28:35 INFO - Running post-action listener: _resource_record_post_action 18:28:35 INFO - ##### 18:28:35 INFO - ##### Running read-buildbot-config step. 18:28:35 INFO - ##### 18:28:35 INFO - Running pre-action listener: _resource_record_pre_action 18:28:35 INFO - Running main action method: read_buildbot_config 18:28:35 INFO - Using buildbot properties: 18:28:35 INFO - { 18:28:35 INFO - "project": "", 18:28:35 INFO - "product": "firefox", 18:28:35 INFO - "script_repo_revision": "production", 18:28:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 18:28:35 INFO - "repository": "", 18:28:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 18:28:35 INFO - "buildid": "20160425155612", 18:28:35 INFO - "pgo_build": "True", 18:28:35 INFO - "basedir": "/builds/slave/test", 18:28:35 INFO - "buildnumber": 253, 18:28:35 INFO - "slavename": "talos-linux64-ix-034", 18:28:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:28:35 INFO - "platform": "ubuntu64_hw", 18:28:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:28:35 INFO - "branch": "mozilla-aurora", 18:28:35 INFO - "repo_path": "releases/mozilla-aurora", 18:28:35 INFO - "revision": "80befee8ec527372547b547cc592e555d3b6c11a", 18:28:35 INFO - "stage_platform": "linux64-pgo", 18:28:35 INFO - "builduid": "1efcd640d5b44eb1aaad13ac6ab462a5", 18:28:35 INFO - "slavebuilddir": "test" 18:28:35 INFO - } 18:28:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 18:28:35 INFO - Running post-action listener: _resource_record_post_action 18:28:35 INFO - ##### 18:28:35 INFO - ##### Running download-and-extract step. 18:28:35 INFO - ##### 18:28:35 INFO - Running pre-action listener: _resource_record_pre_action 18:28:35 INFO - Running main action method: download_and_extract 18:28:35 INFO - mkdir: /builds/slave/test/build/tests 18:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:35 INFO - https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:28:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:28:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:28:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:28:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:28:43 INFO - Downloaded 1361 bytes. 18:28:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 18:28:43 INFO - Using the following test package requirements: 18:28:43 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:28:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:28:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:28:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'jsshell-linux-x86_64.zip'], 18:28:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:28:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 18:28:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:28:43 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:28:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:28:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 18:28:43 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:28:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:28:43 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')]} 18:28:43 INFO - https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:28:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:28:47 INFO - Downloaded 23819812 bytes. 18:28:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:28:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:28:48 INFO - Return code: 0 18:28:48 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:48 INFO - https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:28:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 18:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:28:54 INFO - Downloaded 23819812 bytes. 18:28:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:28:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:28:55 INFO - Return code: 0 18:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:28:55 INFO - https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:28:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:28:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:28:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 18:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:28:57 INFO - Downloaded 10840145 bytes. 18:28:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:28:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:28:57 INFO - Return code: 0 18:28: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')]} 18:28:57 INFO - https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:28:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:28:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:28:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:28:59 INFO - Downloaded 62667789 bytes. 18:28:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:28:59 INFO - mkdir: /builds/slave/test/properties 18:28:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:28:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:28:59 INFO - Contents: 18:28:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 18:29:00 INFO - Running post-action listener: _resource_record_post_action 18:29:00 INFO - Running post-action listener: set_extra_try_arguments 18:29:00 INFO - ##### 18:29:00 INFO - ##### Running populate-webroot step. 18:29:00 INFO - ##### 18:29:00 INFO - Running pre-action listener: _resource_record_pre_action 18:29:00 INFO - Running main action method: populate_webroot 18:29:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:00 INFO - '--tppagecycles', 18:29:00 INFO - '7'], 18:29:00 INFO - 'tests': ['tsvgm']}}, 18:29:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:29:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['damp', 'tps']}, 18:29:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'tests': ['damp', 'tps']}, 18:29:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['dromaeo_dom']}, 18:29:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['a11yr', 18:29:00 INFO - 'ts_paint', 18:29:00 INFO - 'tpaint', 18:29:00 INFO - 'sessionrestore', 18:29:00 INFO - 'sessionrestore_no_auto_restore', 18:29:00 INFO - 'tabpaint']}, 18:29:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['tsvgx', 18:29:00 INFO - 'tsvgr_opacity', 18:29:00 INFO - 'tart', 18:29:00 INFO - 'tscrollx', 18:29:00 INFO - 'cart']}, 18:29:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:00 INFO - 'tsvgr_opacity', 18:29:00 INFO - 'tart', 18:29:00 INFO - 'tscrollx', 18:29:00 INFO - 'cart']}, 18:29:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'talos_options': ['--disable-e10s'], 18:29:00 INFO - 'tests': ['tp5o']}, 18:29:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'tests': ['tp5o']}, 18:29:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'talos_options': ['--disable-e10s', 18:29:00 INFO - '--xperf_path', 18:29:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:00 INFO - 'tests': ['tp5n']}, 18:29:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:00 INFO - 'talos_options': ['--xperf_path', 18:29:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:00 INFO - 'tests': ['tp5n']}}, 18:29:00 INFO - 'talos.zip': {'path': '', 18:29:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:00 INFO - Running post-action listener: _resource_record_post_action 18:29:00 INFO - ##### 18:29:00 INFO - ##### Running create-virtualenv step. 18:29:00 INFO - ##### 18:29:00 INFO - Running pre-action listener: _resource_record_pre_action 18:29:00 INFO - Running main action method: create_virtualenv 18:29:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:00 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 18:29:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:00 INFO - Using real prefix '/usr' 18:29:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:03 INFO - Installing pip.................done. 18:29:03 INFO - Return code: 0 18:29:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:03 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:03 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 18:29:03 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 18:29:03 INFO - Using env: {'DISPLAY': ':0', 18:29:03 INFO - 'HOME': '/home/cltbld', 18:29:03 INFO - 'LANG': 'en_US.UTF-8', 18:29:03 INFO - 'LANGUAGE': 'en_US:en', 18:29:03 INFO - 'LOGNAME': 'cltbld', 18:29:03 INFO - 'MAIL': '/var/mail/cltbld', 18:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:03 INFO - 'MOZ_NO_REMOTE': '1', 18:29:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:03 INFO - 'NO_EM_RESTART': '1', 18:29:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:03 INFO - 'PWD': '/builds/slave/test', 18:29:03 INFO - 'SHELL': '/bin/bash', 18:29:03 INFO - 'SHLVL': '1', 18:29:03 INFO - 'TERM': 'linux', 18:29:03 INFO - 'TMOUT': '86400', 18:29:03 INFO - 'USER': 'cltbld', 18:29:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:03 INFO - '_': '/tools/buildbot/bin/python'} 18:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:03 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 18:29:03 INFO - Cleaning up... 18:29:03 INFO - Return code: 0 18:29:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:03 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:03 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 18:29:03 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 18:29:03 INFO - Using env: {'DISPLAY': ':0', 18:29:03 INFO - 'HOME': '/home/cltbld', 18:29:03 INFO - 'LANG': 'en_US.UTF-8', 18:29:03 INFO - 'LANGUAGE': 'en_US:en', 18:29:03 INFO - 'LOGNAME': 'cltbld', 18:29:03 INFO - 'MAIL': '/var/mail/cltbld', 18:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:03 INFO - 'MOZ_NO_REMOTE': '1', 18:29:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:03 INFO - 'NO_EM_RESTART': '1', 18:29:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:03 INFO - 'PWD': '/builds/slave/test', 18:29:03 INFO - 'SHELL': '/bin/bash', 18:29:03 INFO - 'SHLVL': '1', 18:29:03 INFO - 'TERM': 'linux', 18:29:03 INFO - 'TMOUT': '86400', 18:29:03 INFO - 'USER': 'cltbld', 18:29:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:03 INFO - '_': '/tools/buildbot/bin/python'} 18:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:03 INFO - Downloading/unpacking psutil>=0.7.1 18:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:29:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:05 INFO - Installing collected packages: psutil 18:29:05 INFO - Running setup.py install for psutil 18:29:05 INFO - building 'psutil._psutil_linux' extension 18:29:05 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 18:29:05 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 18:29:05 INFO - building 'psutil._psutil_posix' extension 18:29:05 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 18:29:06 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 18:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:06 INFO - Successfully installed psutil 18:29:06 INFO - Cleaning up... 18:29:06 INFO - Return code: 0 18:29:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:29:06 INFO - Using env: {'DISPLAY': ':0', 18:29:06 INFO - 'HOME': '/home/cltbld', 18:29:06 INFO - 'LANG': 'en_US.UTF-8', 18:29:06 INFO - 'LANGUAGE': 'en_US:en', 18:29:06 INFO - 'LOGNAME': 'cltbld', 18:29:06 INFO - 'MAIL': '/var/mail/cltbld', 18:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:06 INFO - 'MOZ_NO_REMOTE': '1', 18:29:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:06 INFO - 'NO_EM_RESTART': '1', 18:29:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:06 INFO - 'PWD': '/builds/slave/test', 18:29:06 INFO - 'SHELL': '/bin/bash', 18:29:06 INFO - 'SHLVL': '1', 18:29:06 INFO - 'TERM': 'linux', 18:29:06 INFO - 'TMOUT': '86400', 18:29:06 INFO - 'USER': 'cltbld', 18:29:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:06 INFO - '_': '/tools/buildbot/bin/python'} 18:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:29:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:29:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:29:08 INFO - Installing collected packages: mozsystemmonitor 18:29:08 INFO - Running setup.py install for mozsystemmonitor 18:29:08 INFO - Successfully installed mozsystemmonitor 18:29:08 INFO - Cleaning up... 18:29:08 INFO - Return code: 0 18:29:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:29:08 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')]} 18:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:08 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')]} 18:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:08 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:08 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 18:29:08 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 18:29:08 INFO - Using env: {'DISPLAY': ':0', 18:29:08 INFO - 'HOME': '/home/cltbld', 18:29:08 INFO - 'LANG': 'en_US.UTF-8', 18:29:08 INFO - 'LANGUAGE': 'en_US:en', 18:29:08 INFO - 'LOGNAME': 'cltbld', 18:29:08 INFO - 'MAIL': '/var/mail/cltbld', 18:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:08 INFO - 'MOZ_NO_REMOTE': '1', 18:29:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:08 INFO - 'NO_EM_RESTART': '1', 18:29:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:08 INFO - 'PWD': '/builds/slave/test', 18:29:08 INFO - 'SHELL': '/bin/bash', 18:29:08 INFO - 'SHLVL': '1', 18:29:08 INFO - 'TERM': 'linux', 18:29:08 INFO - 'TMOUT': '86400', 18:29:08 INFO - 'USER': 'cltbld', 18:29:08 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:08 INFO - '_': '/tools/buildbot/bin/python'} 18:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:08 INFO - Downloading/unpacking blobuploader==1.2.4 18:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:10 INFO - Downloading blobuploader-1.2.4.tar.gz 18:29:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:29:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:29:10 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 18:29:10 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 18:29:10 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 18:29:10 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 18:29:10 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 18:29:10 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 18:29:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:29:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:11 INFO - Downloading docopt-0.6.1.tar.gz 18:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:29:11 INFO - Installing collected packages: blobuploader, requests, docopt 18:29:11 INFO - Running setup.py install for blobuploader 18:29:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:29:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:29:11 INFO - Running setup.py install for requests 18:29:11 INFO - Running setup.py install for docopt 18:29:11 INFO - Successfully installed blobuploader requests docopt 18:29:11 INFO - Cleaning up... 18:29:11 INFO - Return code: 0 18:29:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:11 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:11 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 18:29:11 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 18:29:11 INFO - Using env: {'DISPLAY': ':0', 18:29:11 INFO - 'HOME': '/home/cltbld', 18:29:11 INFO - 'LANG': 'en_US.UTF-8', 18:29:11 INFO - 'LANGUAGE': 'en_US:en', 18:29:11 INFO - 'LOGNAME': 'cltbld', 18:29:11 INFO - 'MAIL': '/var/mail/cltbld', 18:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:11 INFO - 'MOZ_NO_REMOTE': '1', 18:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:11 INFO - 'NO_EM_RESTART': '1', 18:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:11 INFO - 'PWD': '/builds/slave/test', 18:29:11 INFO - 'SHELL': '/bin/bash', 18:29:11 INFO - 'SHLVL': '1', 18:29:11 INFO - 'TERM': 'linux', 18:29:11 INFO - 'TMOUT': '86400', 18:29:11 INFO - 'USER': 'cltbld', 18:29:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:11 INFO - '_': '/tools/buildbot/bin/python'} 18:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:29:12 INFO - Running setup.py (path:/tmp/pip-FL_Yog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:29:12 INFO - Running setup.py (path:/tmp/pip-P7SbHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:29:12 INFO - Running setup.py (path:/tmp/pip-7LWQrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:29:12 INFO - Running setup.py (path:/tmp/pip-raOJPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:29:12 INFO - Running setup.py (path:/tmp/pip-PQqkcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:29:12 INFO - Running setup.py (path:/tmp/pip-n2EpP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:29:12 INFO - Running setup.py (path:/tmp/pip-KQap2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:29:12 INFO - Running setup.py (path:/tmp/pip-uNS69B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:29:12 INFO - Running setup.py (path:/tmp/pip-MfuzrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:29:12 INFO - Running setup.py (path:/tmp/pip-xN3Mx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:29:12 INFO - Running setup.py (path:/tmp/pip-KkT4Ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:29:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:29:12 INFO - Running setup.py (path:/tmp/pip-BkJV9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:29:13 INFO - Running setup.py (path:/tmp/pip-01GgUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:29:13 INFO - Running setup.py (path:/tmp/pip-Irhxo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:13 INFO - Running setup.py (path:/tmp/pip-H1M1pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:29:13 INFO - Running setup.py (path:/tmp/pip-gGcIsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:29:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:29:13 INFO - Running setup.py (path:/tmp/pip-BhZEhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:29:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:29:13 INFO - Running setup.py install for manifestparser 18:29:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:29:13 INFO - Running setup.py install for mozcrash 18:29:13 INFO - Running setup.py install for mozdebug 18:29:13 INFO - Running setup.py install for mozdevice 18:29:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:29:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:29:13 INFO - Running setup.py install for mozfile 18:29:13 INFO - Running setup.py install for mozhttpd 18:29:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozinfo 18:29:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozInstall 18:29:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozleak 18:29:14 INFO - Running setup.py install for mozlog 18:29:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for moznetwork 18:29:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozprocess 18:29:14 INFO - Running setup.py install for mozprofile 18:29:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozrunner 18:29:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:29:14 INFO - Running setup.py install for mozscreenshot 18:29:14 INFO - Running setup.py install for moztest 18:29:15 INFO - Running setup.py install for mozversion 18:29:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:29:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:29:15 INFO - Cleaning up... 18:29:15 INFO - Return code: 0 18:29:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29:15 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')]} 18:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:15 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')]} 18:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:15 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:15 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 18:29:15 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 18:29:15 INFO - Using env: {'DISPLAY': ':0', 18:29:15 INFO - 'HOME': '/home/cltbld', 18:29:15 INFO - 'LANG': 'en_US.UTF-8', 18:29:15 INFO - 'LANGUAGE': 'en_US:en', 18:29:15 INFO - 'LOGNAME': 'cltbld', 18:29:15 INFO - 'MAIL': '/var/mail/cltbld', 18:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:15 INFO - 'MOZ_NO_REMOTE': '1', 18:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:15 INFO - 'NO_EM_RESTART': '1', 18:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:15 INFO - 'PWD': '/builds/slave/test', 18:29:15 INFO - 'SHELL': '/bin/bash', 18:29:15 INFO - 'SHLVL': '1', 18:29:15 INFO - 'TERM': 'linux', 18:29:15 INFO - 'TMOUT': '86400', 18:29:15 INFO - 'USER': 'cltbld', 18:29:15 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:15 INFO - '_': '/tools/buildbot/bin/python'} 18:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:29:15 INFO - Running setup.py (path:/tmp/pip-fe71AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:29:15 INFO - Running setup.py (path:/tmp/pip-SAWKJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:29:15 INFO - Running setup.py (path:/tmp/pip-beVk3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:29:15 INFO - Running setup.py (path:/tmp/pip-Po14jE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:29:15 INFO - Running setup.py (path:/tmp/pip-ZM8AF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:29:15 INFO - Running setup.py (path:/tmp/pip-anQ16G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:29:15 INFO - Running setup.py (path:/tmp/pip-_fdboG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:29:15 INFO - Running setup.py (path:/tmp/pip-rMfe2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:29:15 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)) 18:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:29:15 INFO - Running setup.py (path:/tmp/pip-rCOKQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:29:16 INFO - Running setup.py (path:/tmp/pip-TXVQaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:29:16 INFO - Running setup.py (path:/tmp/pip-OKQZ_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:29:16 INFO - Running setup.py (path:/tmp/pip-pv8G5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:29:16 INFO - Running setup.py (path:/tmp/pip-KKVFoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:29:16 INFO - Running setup.py (path:/tmp/pip-BUzwud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:16 INFO - Running setup.py (path:/tmp/pip-UoUVht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:29:16 INFO - Running setup.py (path:/tmp/pip-BFs5We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:29:16 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)) 18:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:29:16 INFO - Running setup.py (path:/tmp/pip-cO_Vix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:29:16 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)) 18:29:16 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)) 18:29:16 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)) 18:29:16 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)) 18:29:16 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)) 18:29:16 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)) 18:29:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:18 INFO - Downloading blessings-1.6.tar.gz 18:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:29:18 INFO - Installing collected packages: blessings 18:29:18 INFO - Running setup.py install for blessings 18:29:18 INFO - Successfully installed blessings 18:29:18 INFO - Cleaning up... 18:29:18 INFO - Return code: 0 18:29:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:29:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:29:18 INFO - Reading from file tmpfile_stdout 18:29:18 INFO - Current package versions: 18:29:18 INFO - argparse == 1.2.1 18:29:18 INFO - blessings == 1.6 18:29:18 INFO - blobuploader == 1.2.4 18:29:18 INFO - docopt == 0.6.1 18:29:18 INFO - manifestparser == 1.1 18:29:18 INFO - mozInstall == 1.12 18:29:18 INFO - mozcrash == 0.17 18:29:18 INFO - mozdebug == 0.1 18:29:18 INFO - mozdevice == 0.48 18:29:18 INFO - mozfile == 1.2 18:29:18 INFO - mozhttpd == 0.7 18:29:18 INFO - mozinfo == 0.9 18:29:18 INFO - mozleak == 0.1 18:29:18 INFO - mozlog == 3.1 18:29:18 INFO - moznetwork == 0.27 18:29:18 INFO - mozprocess == 0.22 18:29:18 INFO - mozprofile == 0.28 18:29:18 INFO - mozrunner == 6.11 18:29:18 INFO - mozscreenshot == 0.1 18:29:18 INFO - mozsystemmonitor == 0.0 18:29:18 INFO - moztest == 0.7 18:29:18 INFO - mozversion == 1.4 18:29:18 INFO - psutil == 3.1.1 18:29:18 INFO - requests == 1.2.3 18:29:18 INFO - wsgiref == 0.1.2 18:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:18 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:18 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 18:29:18 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 18:29:19 INFO - Using env: {'DISPLAY': ':0', 18:29:19 INFO - 'HOME': '/home/cltbld', 18:29:19 INFO - 'LANG': 'en_US.UTF-8', 18:29:19 INFO - 'LANGUAGE': 'en_US:en', 18:29:19 INFO - 'LOGNAME': 'cltbld', 18:29:19 INFO - 'MAIL': '/var/mail/cltbld', 18:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:19 INFO - 'MOZ_NO_REMOTE': '1', 18:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:19 INFO - 'NO_EM_RESTART': '1', 18:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:19 INFO - 'PWD': '/builds/slave/test', 18:29:19 INFO - 'SHELL': '/bin/bash', 18:29:19 INFO - 'SHLVL': '1', 18:29:19 INFO - 'TERM': 'linux', 18:29:19 INFO - 'TMOUT': '86400', 18:29:19 INFO - 'USER': 'cltbld', 18:29:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:19 INFO - '_': '/tools/buildbot/bin/python'} 18:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 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)) 18:29:19 INFO - Cleaning up... 18:29:19 INFO - Return code: 0 18:29:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:29:19 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')]} 18:29:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:19 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')]} 18:29:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:19 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:19 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 18:29:19 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 18:29:19 INFO - Using env: {'DISPLAY': ':0', 18:29:19 INFO - 'HOME': '/home/cltbld', 18:29:19 INFO - 'LANG': 'en_US.UTF-8', 18:29:19 INFO - 'LANGUAGE': 'en_US:en', 18:29:19 INFO - 'LOGNAME': 'cltbld', 18:29:19 INFO - 'MAIL': '/var/mail/cltbld', 18:29:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:19 INFO - 'MOZ_NO_REMOTE': '1', 18:29:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:19 INFO - 'NO_EM_RESTART': '1', 18:29:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:19 INFO - 'PWD': '/builds/slave/test', 18:29:19 INFO - 'SHELL': '/bin/bash', 18:29:19 INFO - 'SHLVL': '1', 18:29:19 INFO - 'TERM': 'linux', 18:29:19 INFO - 'TMOUT': '86400', 18:29:19 INFO - 'USER': 'cltbld', 18:29:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:19 INFO - '_': '/tools/buildbot/bin/python'} 18:29:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:19 INFO - Downloading/unpacking jsonschema 18:29:19 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 18:29:19 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 18:29:19 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 18:29:19 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 18:29:19 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 18:29:19 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 18:29:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:29:21 INFO - Downloading/unpacking functools32 (from jsonschema) 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:29:21 INFO - warning: no files found matching '*.txt' 18:29:21 INFO - no previously-included directories found matching 'build' 18:29:21 INFO - no previously-included directories found matching 'dist' 18:29:21 INFO - no previously-included directories found matching '.git*' 18:29:21 INFO - Installing collected packages: jsonschema, functools32 18:29:21 INFO - Running setup.py install for functools32 18:29:21 INFO - warning: no files found matching '*.txt' 18:29:21 INFO - no previously-included directories found matching 'build' 18:29:21 INFO - no previously-included directories found matching 'dist' 18:29:21 INFO - no previously-included directories found matching '.git*' 18:29:21 INFO - Successfully installed jsonschema functools32 18:29:21 INFO - Cleaning up... 18:29:21 INFO - Return code: 0 18:29:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:21 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 0x7f1644c86f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22bb5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', '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 18:29:21 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 18:29:21 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 18:29:21 INFO - Using env: {'DISPLAY': ':0', 18:29:21 INFO - 'HOME': '/home/cltbld', 18:29:21 INFO - 'LANG': 'en_US.UTF-8', 18:29:21 INFO - 'LANGUAGE': 'en_US:en', 18:29:21 INFO - 'LOGNAME': 'cltbld', 18:29:21 INFO - 'MAIL': '/var/mail/cltbld', 18:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:21 INFO - 'MOZ_NO_REMOTE': '1', 18:29:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:21 INFO - 'NO_EM_RESTART': '1', 18:29:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:21 INFO - 'PWD': '/builds/slave/test', 18:29:21 INFO - 'SHELL': '/bin/bash', 18:29:21 INFO - 'SHLVL': '1', 18:29:21 INFO - 'TERM': 'linux', 18:29:21 INFO - 'TMOUT': '86400', 18:29:21 INFO - 'USER': 'cltbld', 18:29:21 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:21 INFO - '_': '/tools/buildbot/bin/python'} 18:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:21 INFO - Downloading/unpacking flake8 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:21 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 18:29:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:29:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:29:23 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 18:29:23 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 18:29:23 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 18:29:23 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 18:29:23 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 18:29:23 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 18:29:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:29:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 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 18:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:29:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:29:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:29:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:29:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:29:24 INFO - no previously-included directories found matching 'docs/_build' 18:29:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:29:24 INFO - Running setup.py install for pep8 18:29:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:29:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:29:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:29:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:29:24 INFO - no previously-included directories found matching 'docs/_build' 18:29:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:29:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:29:24 INFO - Cleaning up... 18:29:24 INFO - Return code: 0 18:29:24 INFO - Running post-action listener: _resource_record_post_action 18:29:24 INFO - Running post-action listener: _start_resource_monitoring 18:29:24 INFO - Starting resource monitoring. 18:29:24 INFO - ##### 18:29:24 INFO - ##### Running install step. 18:29:24 INFO - ##### 18:29:24 INFO - Running pre-action listener: _resource_record_pre_action 18:29:24 INFO - Running main action method: install 18:29:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:29:25 INFO - Reading from file tmpfile_stdout 18:29:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:29:25 INFO - Reading from file tmpfile_stdout 18:29:25 INFO - Output received: 18:29:25 INFO - Usage: mozinstall [options] installer 18:29:25 INFO - Options: 18:29:25 INFO - -h, --help show this help message and exit 18:29:25 INFO - -d DEST, --destination=DEST 18:29:25 INFO - Directory to install application into. [default: 18:29:25 INFO - "/builds/slave/test"] 18:29:25 INFO - --app=APP Application being installed. [default: firefox] 18:29:25 INFO - mkdir: /builds/slave/test/build/application 18:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:29:40 INFO - Reading from file tmpfile_stdout 18:29:40 INFO - Output received: 18:29:40 INFO - /builds/slave/test/build/application/firefox/firefox 18:29:40 INFO - Running post-action listener: _resource_record_post_action 18:29:40 INFO - ##### 18:29:40 INFO - ##### Running run-tests step. 18:29:40 INFO - ##### 18:29:40 INFO - Running pre-action listener: _resource_record_pre_action 18:29:40 INFO - Running main action method: run_tests 18:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:29:40 INFO - Python 2.7.3 18:29:40 INFO - Return code: 0 18:29:40 INFO - grabbing minidump binary from tooltool 18:29:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:29:40 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 0x234a410>, '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 0x234b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x234b7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:29:40 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 18:29:40 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 18:29:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:29:40 INFO - Return code: 0 18:29:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:29:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:29:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:29:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:29:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:29:41 INFO - Return code: 0 18:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:29:41 INFO - Using env: {'DISPLAY': ':0', 18:29:41 INFO - 'HOME': '/home/cltbld', 18:29:41 INFO - 'LANG': 'en_US.UTF-8', 18:29:41 INFO - 'LANGUAGE': 'en_US:en', 18:29:41 INFO - 'LOGNAME': 'cltbld', 18:29:41 INFO - 'MAIL': '/var/mail/cltbld', 18:29:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:29:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:41 INFO - 'MOZ_NO_REMOTE': '1', 18:29:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:41 INFO - 'NO_EM_RESTART': '1', 18:29:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:41 INFO - 'PWD': '/builds/slave/test', 18:29:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:29:41 INFO - 'SHELL': '/bin/bash', 18:29:41 INFO - 'SHLVL': '1', 18:29:41 INFO - 'TERM': 'linux', 18:29:41 INFO - 'TMOUT': '86400', 18:29:41 INFO - 'USER': 'cltbld', 18:29:41 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840', 18:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:41 INFO - '_': '/tools/buildbot/bin/python'} 18:29:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:29:41 INFO - mozversion application_buildid: 20160425155612 18:29:41 INFO - mozversion application_changeset: 80befee8ec527372547b547cc592e555d3b6c11a 18:29:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:29:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:29:41 INFO - mozversion application_name: Firefox 18:29:41 INFO - mozversion application_remotingname: firefox-dev 18:29:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:29:41 INFO - mozversion application_vendor: Mozilla 18:29:41 INFO - mozversion application_version: 48.0a2 18:29:41 INFO - mozversion platform_buildid: 20160425155612 18:29:41 INFO - mozversion platform_changeset: 80befee8ec527372547b547cc592e555d3b6c11a 18:29:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:29:41 INFO - mozversion platform_version: 48.0a2 18:29:41 INFO - using testdate: 1461634181 18:29:41 INFO - actual date: 1461634181 18:29:41 INFO - starting webserver on 'localhost:56278' 18:29:41 INFO - SUITE-START | Running 2 tests 18:29:41 INFO - TEST-START | dromaeo_css 18:29:41 INFO - operating with platform_type : linux_ 18:29:41 INFO - Initialising browser for dromaeo_css test... 18:29:41 INFO - TEST-INFO | started process 6775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDagbzT/profile http://localhost:56278/getInfo.html) 18:29:45 INFO - PROCESS | 6775 | 18:29:45 INFO - PROCESS | 6775 | (process:6852): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:29:46 INFO - PROCESS | 6775 | __metrics Screen width/height:1600/1200 18:29:46 INFO - PROCESS | 6775 | colorDepth:24 18:29:46 INFO - PROCESS | 6775 | Browser inner width/height: 1024/697 18:29:46 INFO - PROCESS | 6775 | __metrics 18:29:47 ERROR - PROCESS | 6775 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:29:48 INFO - TEST-INFO | 6775: exit 0 18:29:48 INFO - Browser initialized. 18:29:48 INFO - Running cycle 1/1 for dromaeo_css test... 18:29:48 INFO - TEST-INFO | started process 6905 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDagbzT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:50 INFO - PROCESS | 6905 | 18:29:50 INFO - PROCESS | 6905 | (process:6970): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:30:00 INFO - PROCESS | 6905 | RSS: Main: 152698880 18:30:00 INFO - PROCESS | 6905 | 18:30:49 INFO - PROCESS | 6905 | 1461634249925 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] 18:32:57 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56278/tests/dromaeo/cssquery-ext.html) 18:32:58 INFO - PROCESS | 6905 | RSS: Main: 155394048 18:32:58 INFO - PROCESS | 6905 | 18:36:00 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-ext.html (next: http://localhost:56278/tests/dromaeo/cssquery-jquery.html) 18:36:00 INFO - PROCESS | 6905 | RSS: Main: 154578944 18:36:00 INFO - PROCESS | 6905 | 18:39:02 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56278/tests/dromaeo/cssquery-mootools.html) 18:39:03 INFO - PROCESS | 6905 | RSS: Main: 153767936 18:39:03 INFO - PROCESS | 6905 | 18:42:04 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56278/tests/dromaeo/cssquery-prototype.html) 18:42:05 INFO - PROCESS | 6905 | RSS: Main: 153600000 18:42:05 INFO - PROCESS | 6905 | 18:45:07 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56278/tests/dromaeo/cssquery-yui.html) 18:45:08 INFO - PROCESS | 6905 | RSS: Main: 155115520 18:45:08 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | Cycle 1(1): loaded http://localhost:56278/tests/dromaeo/cssquery-yui.html (next: http://localhost:56278/tests/dromaeo/cssquery-dojo.html) 18:48:10 INFO - PROCESS | 6905 | RSS: Main: 156483584 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | __start_tp_report 18:48:10 INFO - PROCESS | 6905 | _x_x_mozilla_page_load 18:48:10 INFO - PROCESS | 6905 | _x_x_mozilla_page_load_details 18:48:10 INFO - PROCESS | 6905 | |i|pagename|runs| 18:48:10 INFO - PROCESS | 6905 | |0;dojo.html;3301.554290170827;3377.628460869304;3390.0507857799826;3398.0655319787056;3400.2859399526096;5519;5558.027345214587;5577.69322687252;5589.329280486337;5617.8033768818095;5535.225068490405;5579.9163012554845;5598.720063996804;5613.242212301338;5623.775048998045;5042.848714538561;5120.718360490171;5137.126688462951;5165.948340516603;5178.5339319461245;5077.619178561623;5193.324867767185;5226.8693282667855;5264.710440925747;5286.04851126797;5024.296598476216;5108.182690769478;5119.257707632391;5193.221016847466;5197.688138711689;5072.289879416885;5105.591552675795;5111.182210846284;5127.641065125443;5136.5890728741615;4975.129352363322;5012.874678133039;5070.29015937769;5076.289319495274;5105.081085404626;5282.181261904411;5409.783608655649;5416.350037995466;5460.180972854064;5569.916451253234;5965.164876917192;6005.909911351333;6006.699665016732;6008.218931538916;6009;5994.8501287467725;6056.42463965927;6073.423024812636;6074.9392506075255;6085.513158947274;5401.513863752242;5490.835274941718;5585.860353491195;5605.607607467479;5643.943560564383;6061.424164704346;6070.453659170696;6077.149199112039;6124.846878828065;6133.294671112879;5834.620749651259;5873.089671100986;5887.4406931341455;5903.085021821625;5908.497777688945;5785.450382213683;5815.88368232633;5869.090291004901;5895.115732640138;5910.497607703307;5860.560457965669;5914.970425147847;5917.142014407887;5920.733566989496;5928.229330187048;5837.532997360201;5868.119782032729;5906.852328691731;5928.081147422156;5938.762449502058;4550.294704320836;4594.609458196091;4596.195665758479;4608.147492713859;4625.629949604024;4016.377461493473;4027.0536423940353;4046.1503084352616;4052.3313653247474;4055.270051390778;5585.748641311151;5586;5594.049011668027;5645.576581756384;5666.065099258659;5919.585629006015;5948.881022379528;5987.610805297729;6040.818775436744;6122.112293717386;5764.5676574257095;5776;5781.971090144607;5789.45000224978;5795.13073039039;5024.698518088926;5118.59051275897;5183.377994640668;5191;5205.297284866495;6246.156768836149;6304.432601065926;6337.6197428154455;6345;6345.0482427636225;5974.372690867396;6031.306399764083;6037.818865434044;6040.305364883095;6057.42454466825;6127.724252408563;6169.352218017044;6255.155554000152;6277.623342599459;6282.591631543935;5898.469137777621;5899.970500147643;5907.527397808251;5909.70451477433;5922.378150294329;5987.700614969322;6007;6016.548758843017;6018.939810601838;6019.518438524995;5648;5706.714664266853;5754.395788442321;5776.393478684678;5846.181534585076;5755.625884317506;5761.905238004766;5801.3038435388025;5810.622309549865;5823.912641310299;5647.915281270702;5671.659700417823;5714.314282286152;5715.371309156073;5730.312362516524;5709.22925405065;5772.913406298993;5849.356570777296;5926.022206335906;5933.821985340447;5584.46947539983;5620.04459241938;5629.437056294338;5631;5646.576506762091 18:48:10 INFO - PROCESS | 6905 | |1;ext.html;668.4017804065361;681.9386255237027;683.2245401469331;688.077975512813;709.5707097206193;42729.14541709164;44773;44835.77582112089;44844.551554484446;44911;45212.547874521326;46078.539214607845;46195;46249.30626040612;46366.30450543245;8801.691940782073;9032.638694452247;9049.140331668481;9062.275017998578;9081.95459022704;8878;8935.597898094602;8947.194752472276;9041.412308199979;9055.320850936174;8941.821163576757;9028.32287578431;9052.773680658003;9055.637774489012;9074.410163339362;8990.37067405282;9000.459972401644;9065.410748301372;9068.501232432214;9073.63705451781;8924.107589241024;9039.864402033974;9048.049954754788;9057.954710226473;9058.048904865025;42111;42293;42566.78716606428;42615.14769704588;42960.14079718388;3612.945805812915;4301.763403012833;4350.350618607332;4396.670249731283;4459.643228541741;30783.538246926313;31150;33134;34051.48922766161;35729.28541429157;4211.642010429117;4229.069604686964;4234.85178018771;4256.212600668886;4325.740455572675;347312;347807;348794;349150;350362;36484.81757591248;37085.81457092751;37118.257634847694;64230.35769642337;64904;38234;38379.2324153521;38408.42387364192;38495.80752096222;38516;15316.081035137928;15465;15538.456154034555;15586.9220653896;15602.609934751496;12715.491380344867;12837.614871553867;12851.807222891664;12852.614421567181;12880.742385152433;9576.856347154799;9601.279904007228;9626.326157169071;9688.612455501701;9717.271204659679;3648.051506608248;3726.6832319252626;3740.401535754266;3740.981295093507;3777.225668737902;1043.8799538053222;1080.4543705428866;1094.8303013032992;1120.4453795371344;1194.0149376764205;33032;33424.83287583595;33914.152146196546;34122.48816267758;34381.48427773586;1171.625079974417;1222.5293262094158;1231.6551365617463;1235.6478403654914;1242.5278394210143;17654.646907061786;17923.820761792475;18003.909980450015;18221;18230.544236394195;353910;420067;424021;424060;508859;64125.67937160284;64737;64942;65071.34928650653;65183;1483.0117313613077;1510.3807438949977;1511.2821409830462;1511.7052174826024;1528.3580896023698;33512.83243583864;34242.31515369729;34257.82871085629;34258.48612270768;34538.481922770676;54816.17775733287;55613;55723;55724.44275557192;55781.721091395906;453842;463178;464335;467073;467491;31525;31729.524057139624;31879.6812031886;31899.521507176945;31969.520457193626;49528.75235623943;51066.701998409415;51294.74352628213;51360.22959655683;51552;41967.1606567873;42276.78861605672;43111.784441077594;43154.56845431506;43918.780406097765;42002.36996444994;42505.36241956435;42883.14233715371;43497.56502435062;43560;42957.14085718331;43293.35059974166;43845.78077109594;44115;44628.77685611551;37461.43807842939;37852.81073594614;38180;38357.04107397337;38666.80666596762;28495.145145645663;28733.712662873102;28822.567661484674;28870.42259154847;29111 18:48:10 INFO - PROCESS | 6905 | |2;jquery.html;429.2316753012108;429.45459266731234;432.48102277267276;434.2314104035856;437.3942090205065;19189.904050479745;19258.90370548147;19864.70202946955;20700.89649551754;20706.482337941557;17676.204570794314;18217.726734099;19338.709919351222;19390.612187756236;19542.902285488588;4800.207965685667;5192.247124167013;5203.063448579254;5209.86975325618;5213.608979326546;5096.4648711885275;5141.1003074462005;5173.508516690908;5174.275601415805;5177.508136727024;4945;5255.973720131413;5256.8685782855355;5257.316548848664;5272;5162.896742065175;5217.817376391827;5220.243064755608;5235.6596821206695;5266.4733526647415;5319.601029922771;5347.652402593819;5366.302380690524;5368.114261146906;5370.758315875795;13375.26436046017;13544;18301;23715.525689486112;23900;2246.2587346175724;2325.6511523271474;2347.342744031657;2378.1557547070715;2380.702412198475;9101.815564264594;9571.569279382444;13526.526571569948;15545.145017024059;15659.921700391538;1939.8933058681766;2066;2093.979060209409;2111.8944052797297;2149.344449942767;82144;84624;84726.15273847245;86334.56832715796;87005.99999999936;10935.56257749681;11127.888721112686;11363.602273920373;11391.658250252505;11462.140339474572;15758.527244182693;15760.92119539395;15790.763138552931;15858.207089645703;15920;7913.9604301978125;8058.637361318754;8091.190880911861;8241.752747417588;8251.133630968661;7565.356944659656;7676.57778822159;7680.270374314431;7689.846203075907;7734.110577283572;4794.376731024944;4822.54050434281;4842.273658951185;4890.437599676012;4936.284238785428;1835.6971099768389;1996.8103030212108;2041.3671761753903;2094.1728017433334;2095.256184054669;976.1263669016191;1018;1048.9737756555994;1123.1913022623698;1136.5510623303749;19157.521061973563;19957.414342490458;20323.491912702004;20349.186032558533;20593.79406205949;1032.7986042721598;1072.4316112460408;1094.6497120921388;1118.3290025984345;1120.624590762104;10458.111060559786;10858.968561959331;10866.873834679925;10868.014861731073;10916.009948161662;593432;613822;617453;620984;626489;87050;91036;91748;92181;92442.07557924469;1104.8397982292522;1107.950142243601;1117.4915413486844;1130.5534313945946;1132.8640563132312;17063.7440438396;17090.72448187324;17142.65714685674;17151.57121071983;17280;38678.22643547057;39173.41239881461;39208;39847;39930.401043984944;53997.190042150185;55754.72122639361;58790;59186;60331;7992.960035199787;8423.241908228043;8550.74347769568;8684.565771711515;8801.867971980297;9465.66870159541;13564.253966031964;13881.37533811001;13918.443262269397;14043.719125617226;11592.47833847462;12028.09789265791;12177.147599667938;12866.292353920624;12887.09790314669;11542.134339924707;11867.525298987945;11874.406279686154;11930.22453540517;12109.394530273627;11592.6522204334;11806.763864722485;11938.044956403293;12369.876301236873;13237.205767653972;8571.528565928933;8780.692675756318;8813.42712723671;8829.470231786114;8969.596368163579;9121;12049.33728644933;12230.14388992762;12417;12588.30764307972 18:48:10 INFO - PROCESS | 6905 | |3;mootools.html;1546.6751982083765;1549.5893588199128;1740.2951804519173;1789.4810504953564;1884.029724691783;7478.887816682741;7764.378849692025;8002.959985200066;8103.068147163078;8136.6745330186795;7430.517016393931;7946.880796788053;8054;8135.1051384347675;8149.551774652392;2283.7830406111475;2303.3780879162614;2320.9419764505856;2321.1759825262043;2333.5449587330477;2325.662778897059;2349.6240601503764;2359.1153317505937;2359.2568340972625;2370.5851475991717;2311.179531266401;2334.4747431827786;2338.5206032763326;2345.108858633717;2357.7524359942217;2239.787220214077;2269.841111122222;2284.451731584416;2288.0275882749766;2294.19703103914;2232.586971410294;2284.4403121235237;2301.620232661609;2303.9308820735237;2304.68886700295;8234.794130146734;8271.1315311892;8293.875591866126;8348.206920342558;8354.373421993314;5017.34774479319;5189.896202075975;5197.194434862602;5211.947880521184;5221.765020574083;3143.4184675835045;3256.495243237302;3360.2271477560053;3361.5293858859777;3368.2758206985372;1182.3497076607916;1191.1780871198846;1209.8003829368145;1219.4024927785322;1231.445849367788;11233.887661123366;11299.717507062307;11302.830457543143;11311.321320720865;11316.886831131666;4492.9550704493195;4505.774711264424;4513.638908887281;4605.723656580616;4629.213033784265;9699.272554558436;9703.466309353053;9703.563339649732;9715.611375544899;9719.85420218683;5019.519241823671;5034.723090229999;5043.798248070036;5058.266551350107;5060.671056381323;4098.262312783728;4562.429696287964;4600.42494688164;4672.415948006499;4692;4100.1184745279315;4120.75275483472;4132.504099508078;4155.667546596265;4188.706790524634;1338.4110991163857;1350.8649135086412;1357.9185248885099;1361.6051345109806;1394.1704685711986;891.4428482198626;920.5259291464901;1058.8041212375736;1059.4120263253967;1079.9028087472166;5764.74058667361;5812.912806307909;5880.176775251468;5922.230110085748;5946.940530594725;457.2295681776218;457.8809509527547;460.470458972178;1065.190455253997;1097.7200813792592;4077.4903137107863;4085.6935729820384;4091.3044782387065;4140.0891803803115;4152.730072545275;9566.282528810367;9936.254780891462;9994.550245238981;10006.749831254278;10025.448600326907;9699.806003879883;9700.466474343979;9778.804423911482;9859.605615775288;9890.604375824887;7763.146053934062;7945.761627151079;7951.363890888715;8011.319037881845;8017.118117007125;5677.772889084556;5758.62568933018;5810.310452862378;5966.522678185648;5981.760729570942;9359.532023398939;9448.574814133242;9489.66786162481;9518.000609936134;9577.281703872377;9161.633534658538;9434.433933963985;9479.099485548863;9483.905160948303;9522.047795220422;6829.93170068293;7187.856242875008;7348.632568371667;7463.253674632493;7525.435592330707;8015.839683206186;8034.276915077671;8037;8119.3098586618435;8139.389545784208;7105;7309.671064801991;7329.963350183429;7338.816529586803;7452.925470745223;7122.821929451805;7165.176004759311;7177.928220717935;7194.280571942973;7253;6895.482838787011;6912.827179320558;6935.098437203132;6946.826329341807;6957.8956315654295;4927.531884470969;4936.963237720119;5000.824971125993;5049.293098966221;5155.097857874932;4947.529984651453;4990.700557966534;5008.073506401327;5058.317127187783;5076.466970967996 18:48:10 INFO - PROCESS | 6905 | |4;prototype.html;335.6811029521955;344.26842958712734;349.88978471781377;353.3039911374591;357.1660173494889;5481.862953426162;5629.662220266787;5633.859153521164;5673.886522269552;5680.693440508686;5545.170093869014;5552.611317207797;5552.972235138819;5606.243157173781;5625.324961004675;4009.9799501002453;4334.5882141196535;4351.825926962925;4355.934660980088;4361.280388735855;4271.722338048033;4329.653627709791;4335.176316499871;4354.608085272325;4379.4963579188325;4294.398784170218;4302.48370195577;4310.784460776964;4317.633001194887;4322.913541729164;4305.117450922569;4319.913601727979;4324.96200911782;4336.522982471925;4353.455818022747;3618.6200448952745;3803.6497043549693;3831.5593706723525;3833.1375440525658;3840.8079596020366;5337.3061502004875;5365.141577347645;5402.486763757437;5420.13277875538;5420.186971954199;4935.259711043336;4938.3580134582635;4942.678725882806;4981.850544483671;4988.251762235657;5729.398413166641;5863.941360586382;5904.675242861684;5926.7925622603;5934.554908381845;4147.170565886805;4184.205001049791;4188.225178342016;4208.842568293713;4219.198352313051;5883.881542782289;6110.572259941806;6136.570440069197;6199.318075011745;6216.160818289518;4267.893302667459;4289.892752681208;4292.420523229324;4298.527162012176;4301.91396172081;4348.7390756554705;4360.738355698605;4364.214441400515;4369.737815731066;4379.737215767064;4944.159492886218;4948.851534453972;4975.751212439363;4985.675931064542;5002.299678045077;5769.567282453832;5933.881322373529;5968.552358573038;5976.491998180117;6002.7298771554515;1534.562649644868;1550.4418409372618;1552.4256025270722;1554.4947891935167;1563.5074951390502;1012.3419777144915;1014.9238807089498;1020.5203554329453;1049.9790004199874;1066.6106870992032;5675.602707810413;5853.912191317134;5932.970335148383;5936.347001829795;6242.219722534684;6110.205673262448;6138.158552434252;6159.076138579249;6185.257769067741;6203.751849926042;5656.264685590929;5855.707214639251;5869.501092407193;5871.236739223874;5875.794347197828;3482.5472688550326;3492.161881148506;3510.3330367230146;3515.789052656849;3520.4015317395592;6098.237720284965;6898.1377327834025;6913.481488888354;6929.5842249465195;6977.581345119309;6276.340984196685;6280.214973128359;6307.779227727007;6351.555391122577;6371.171747672865;6424.871502569922;6507.479401647857;6515.250746164157;6516.934830651633;6560.278369379364;5976.432238937294;6113.480354169946;6152.323244443197;6158.907616385847;6174.536909731698;6093.878122437615;6115.785947491817;6131.724072416663;6143.539234557516;6156.1381406604005;6233.625982441068;6455.4512866406885;6486.318936511599;6493.675316234264;6524.4454221389815;4241.109367032957;4248.511421186603;4253.617174454314;4266.418816616027;4283.293256612624;6120.265568131853;6122.234720659918;6136.662483563447;6140.3245642978345;6146.78486252979;5756.913646295226;5889.617174883619;6006.189164462917;6009.158717779427;6015.248093988252;5963.522918166623;5999.490298175616;6000.69996500182;6005.579609427298;6008.399160083956;5674.2623458950075;5741.25363702716;5806.531481343897;5894.970525147518;5920.3062606514795;5976.790812321722;5984.281886173687;6019.819405417845;6022.759089636541;6027.427394397525;5839.591228613956;5844.620099693507;5851.356350801323;5861.179434879035;5919.881602368015 18:48:10 INFO - PROCESS | 6905 | |5;yui.html;769.5844244108181;782.0380931454315;830.3092752714184;892.7232557907049;913.0549880873295;14704;15321.540353789404;15342.38630454782;15346.232688365564;15354.616134596641;11921.880781192185;13831.654208644788;13922.791158132635;13943;13969;839.5634270179503;881.5415983688478;884.8672699095155;888.9377743557953;891.6700820696351;876.2814492116468;877.3814460805118;883.2713011765287;885.0662551008684;885.1325700813206;925.4771054354302;932.4638332958576;933.7525555727738;934.6308208257734;935.7239614313794;923.044648788504;923.4413180026122;923.6166990698852;926.5552534783275;927.4898805656895;677.9932200677979;681.686424244848;683.0880774166444;683.2074793239818;683.5454422808876;14842.332095055748;15187.392504299814;15217.923910380488;15252;15272.083674979536;14481.78277325841;14678.779818302733;14893.106413615218;14894.329755161043;15179.316930738143;12221.237667628691;12329.69175770604;12344.444499997522;12404.13171078025;12550.68623284416;4016.15660711251;4039.3738970459626;4096.221717873595;4109.856155034589;4154.335306350999;52145.2178217327;53112.203316950276;53219.733901330634;53579.732101339636;54400.18399723968;11516.30902145874;11596.88403115958;11694.239874408306;11705.180637355303;11872.703182420508;20846.583068338547;20852.478688032617;20895.164193432094;21152.682709759367;21164.153433862783;8760.649574017096;8813.823723525493;8823.161799629024;9059.36584439096;9185;4901.460839307673;4979.825706100341;5046.9747651261505;5065.164247899129;5122.334096567422;3523.066387407339;3603.8198090095916;3609.296187243462;3612;3615.1142969972525;621.6114928169894;631.5768435148461;669.7388018672722;690.6477696374885;692.9930700693029;663.9502037347219;724.5695393737192;739.7263012685233;744.508624307965;752.2665401233753;9859.35914165577;10158.847617285746;10180.847287290548;10200.846987295197;10202.540885660163;626.8558231606756;662.7580932959435;773.0739152308486;782.4483738964017;804.3685706720197;4427.285194681254;4606.884827879263;4681.414823147106;4699.107169637758;4817.686850354786;72120.63939680373;73330.63334683292;73359.63320183472;73441.16397090115;73670.63164684249;60966.390336096956;62565.37434625687;62954;63259.05111423332;65520;56309.43690563042;56853;56967.14549281764;57113.14330285049;57934.42065579374;8596.355273354398;9010.684626038057;9046.321525885716;9050.140236677506;9065.274778017874;37338.8133059333;37722.43416348702;37779.81110094542;37899.43150852684;38457;47046;49661;51215;51739.223911640605;52148.739256304994;11762.411879405825;12097.971672407944;12684.23894566329;12832.358382080673;13145.934270328587;13275.336233188109;13325.400356984153;13659.590212293648;13695.246761428218;14440.133591984515;13248.403821828233;13254.801177982145;13485.528006519724;13728.93135534316;14000;12722.491100355883;12902.225866447669;13067.411966461343;13458.125221860548;14399.712005760035;12409.131360804657;12660.936695316464;12900.806487902877;13364.599062027766;13708.451661933412;10919.235653504178;10932;11052.723681908017;11144.164187685794;11371;10535.104516115909;10742.946285268523;10758.8386174209;10764.515596798006;10842.024217820433 18:48:10 INFO - PROCESS | 6905 | __end_tp_report 18:48:10 INFO - PROCESS | 6905 | __start_cc_report 18:48:10 INFO - PROCESS | 6905 | _x_x_mozilla_cycle_collect,326 18:48:10 INFO - PROCESS | 6905 | __end_cc_report 18:48:10 INFO - PROCESS | 6905 | __startTimestamp1461635290951__endTimestamp 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | ------- Summary: start ------- 18:48:10 INFO - PROCESS | 6905 | Number of tests: 6 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#0] dojo.html Cycles:165 Average:5550.99 Median:5730.31 stddev:614.44 (10.7%) stddev-sans-first:590.44 18:48:10 INFO - PROCESS | 6905 | Values: 3301.6 3377.6 3390.1 3398.1 3400.3 5519.0 5558.0 5577.7 5589.3 5617.8 5535.2 5579.9 5598.7 5613.2 5623.8 5042.8 5120.7 5137.1 5165.9 5178.5 5077.6 5193.3 5226.9 5264.7 5286.0 5024.3 5108.2 5119.3 5193.2 5197.7 5072.3 5105.6 5111.2 5127.6 5136.6 4975.1 5012.9 5070.3 5076.3 5105.1 5282.2 5409.8 5416.4 5460.2 5569.9 5965.2 6005.9 6006.7 6008.2 6009.0 5994.9 6056.4 6073.4 6074.9 6085.5 5401.5 5490.8 5585.9 5605.6 5643.9 6061.4 6070.5 6077.1 6124.8 6133.3 5834.6 5873.1 5887.4 5903.1 5908.5 5785.5 5815.9 5869.1 5895.1 5910.5 5860.6 5915.0 5917.1 5920.7 5928.2 5837.5 5868.1 5906.9 5928.1 5938.8 4550.3 4594.6 4596.2 4608.1 4625.6 4016.4 4027.1 4046.2 4052.3 4055.3 5585.7 5586.0 5594.0 5645.6 5666.1 5919.6 5948.9 5987.6 6040.8 6122.1 5764.6 5776.0 5782.0 5789.5 5795.1 5024.7 5118.6 5183.4 5191.0 5205.3 6246.2 6304.4 6337.6 6345.0 6345.0 5974.4 6031.3 6037.8 6040.3 6057.4 6127.7 6169.4 6255.2 6277.6 6282.6 5898.5 5900.0 5907.5 5909.7 5922.4 5987.7 6007.0 6016.5 6018.9 6019.5 5648.0 5706.7 5754.4 5776.4 5846.2 5755.6 5761.9 5801.3 5810.6 5823.9 5647.9 5671.7 5714.3 5715.4 5730.3 5709.2 5772.9 5849.4 5926.0 5933.8 5584.5 5620.0 5629.4 5631.0 5646.6 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#1] ext.html Cycles:180 Average:57655.92 Median:233282.68 stddev:110083.73 (47.2%) stddev-sans-first:110309.39 18:48:10 INFO - PROCESS | 6905 | Values: 668.4 681.9 683.2 688.1 709.6 42729.1 44773.0 44835.8 44844.6 44911.0 45212.5 46078.5 46195.0 46249.3 46366.3 8801.7 9032.6 9049.1 9062.3 9082.0 8878.0 8935.6 8947.2 9041.4 9055.3 8941.8 9028.3 9052.8 9055.6 9074.4 8990.4 9000.5 9065.4 9068.5 9073.6 8924.1 9039.9 9048.0 9058.0 9058.0 42111.0 42293.0 42566.8 42615.1 42960.1 3612.9 4301.8 4350.4 4396.7 4459.6 30783.5 31150.0 33134.0 34051.5 35729.3 4211.6 4229.1 4234.9 4256.2 4325.7 347312.0 347807.0 348794.0 349150.0 350362.0 36484.8 37085.8 37118.3 64230.4 64904.0 38234.0 38379.2 38408.4 38495.8 38516.0 15316.1 15465.0 15538.5 15586.9 15602.6 12715.5 12837.6 12851.8 12852.6 12880.7 9576.9 9601.3 9626.3 9688.6 9717.3 3648.1 3726.7 3740.4 3741.0 3777.2 1043.9 1080.5 1094.8 1120.4 1194.0 33032.0 33424.8 33914.2 34122.5 34381.5 1171.6 1222.5 1231.7 1235.6 1242.5 17654.6 17923.8 18003.9 18221.0 18230.5 353910.0 420067.0 424021.0 424060.0 508859.0 64125.7 64737.0 64942.0 65071.3 65183.0 1483.0 1510.4 1511.3 1511.7 1528.4 33512.8 34242.3 34257.8 34258.5 34538.5 54816.2 55613.0 55723.0 55724.4 55781.7 453842.0 463178.0 464335.0 467073.0 467491.0 31525.0 31729.5 31879.7 31899.5 31969.5 49528.8 51066.7 51294.7 51360.2 51552.0 41967.2 42276.8 43111.8 43154.6 43918.8 42002.4 42505.4 42883.1 43497.6 43560.0 42957.1 43293.4 43845.8 44115.0 44628.8 37461.4 37852.8 38180.0 38357.0 38666.8 28495.1 28733.7 28822.6 28870.4 29111.0 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#2] jquery.html Cycles:180 Average:32430.52 Median:13031.43 stddev:100812.38 (773.6%) stddev-sans-first:101066.54 18:48:10 INFO - PROCESS | 6905 | Values: 429.2 429.5 432.5 434.2 437.4 19189.9 19258.9 19864.7 20700.9 20706.5 17676.2 18217.7 19338.7 19390.6 19542.9 4800.2 5192.2 5203.1 5209.9 5213.6 5096.5 5141.1 5173.5 5174.3 5177.5 4945.0 5256.0 5256.9 5257.3 5272.0 5162.9 5217.8 5220.2 5235.7 5266.5 5319.6 5347.7 5366.3 5368.1 5370.8 13375.3 13544.0 18301.0 23715.5 23900.0 2246.3 2325.7 2347.3 2378.2 2380.7 9101.8 9571.6 13526.5 15545.1 15659.9 1939.9 2066.0 2094.0 2111.9 2149.3 82144.0 84624.0 84726.2 86334.6 87006.0 10935.6 11127.9 11363.6 11391.7 11462.1 15758.5 15760.9 15790.8 15858.2 15920.0 7914.0 8058.6 8091.2 8241.8 8251.1 7565.4 7676.6 7680.3 7689.8 7734.1 4794.4 4822.5 4842.3 4890.4 4936.3 1835.7 1996.8 2041.4 2094.2 2095.3 976.1 1018.0 1049.0 1123.2 1136.6 19157.5 19957.4 20323.5 20349.2 20593.8 1032.8 1072.4 1094.6 1118.3 1120.6 10458.1 10859.0 10866.9 10868.0 10916.0 593432.0 613822.0 617453.0 620984.0 626489.0 87050.0 91036.0 91748.0 92181.0 92442.1 1104.8 1108.0 1117.5 1130.6 1132.9 17063.7 17090.7 17142.7 17151.6 17280.0 38678.2 39173.4 39208.0 39847.0 39930.4 53997.2 55754.7 58790.0 59186.0 60331.0 7993.0 8423.2 8550.7 8684.6 8801.9 9465.7 13564.3 13881.4 13918.4 14043.7 11592.5 12028.1 12177.1 12866.3 12887.1 11542.1 11867.5 11874.4 11930.2 12109.4 11592.7 11806.8 11938.0 12369.9 13237.2 8571.5 8780.7 8813.4 8829.5 8969.6 9121.0 12049.3 12230.1 12417.0 12588.3 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#3] mootools.html Cycles:180 Average:5514.89 Median:5053.78 stddev:2987.84 (59.1%) stddev-sans-first:2981.34 18:48:10 INFO - PROCESS | 6905 | Values: 1546.7 1549.6 1740.3 1789.5 1884.0 7478.9 7764.4 8003.0 8103.1 8136.7 7430.5 7946.9 8054.0 8135.1 8149.6 2283.8 2303.4 2320.9 2321.2 2333.5 2325.7 2349.6 2359.1 2359.3 2370.6 2311.2 2334.5 2338.5 2345.1 2357.8 2239.8 2269.8 2284.5 2288.0 2294.2 2232.6 2284.4 2301.6 2303.9 2304.7 8234.8 8271.1 8293.9 8348.2 8354.4 5017.3 5189.9 5197.2 5211.9 5221.8 3143.4 3256.5 3360.2 3361.5 3368.3 1182.3 1191.2 1209.8 1219.4 1231.4 11233.9 11299.7 11302.8 11311.3 11316.9 4493.0 4505.8 4513.6 4605.7 4629.2 9699.3 9703.5 9703.6 9715.6 9719.9 5019.5 5034.7 5043.8 5058.3 5060.7 4098.3 4562.4 4600.4 4672.4 4692.0 4100.1 4120.8 4132.5 4155.7 4188.7 1338.4 1350.9 1357.9 1361.6 1394.2 891.4 920.5 1058.8 1059.4 1079.9 5764.7 5812.9 5880.2 5922.2 5946.9 457.2 457.9 460.5 1065.2 1097.7 4077.5 4085.7 4091.3 4140.1 4152.7 9566.3 9936.3 9994.6 10006.7 10025.4 9699.8 9700.5 9778.8 9859.6 9890.6 7763.1 7945.8 7951.4 8011.3 8017.1 5677.8 5758.6 5810.3 5966.5 5981.8 9359.5 9448.6 9489.7 9518.0 9577.3 9161.6 9434.4 9479.1 9483.9 9522.0 6829.9 7187.9 7348.6 7463.3 7525.4 8015.8 8034.3 8037.0 8119.3 8139.4 7105.0 7309.7 7330.0 7338.8 7452.9 7122.8 7165.2 7177.9 7194.3 7253.0 6895.5 6912.8 6935.1 6946.8 6957.9 4927.5 4937.0 5000.8 5049.3 5155.1 4947.5 4990.7 5008.1 5058.3 5076.5 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#4] prototype.html Cycles:180 Average:5025.75 Median:5674.93 stddev:1505.03 (26.5%) stddev-sans-first:1467.50 18:48:10 INFO - PROCESS | 6905 | Values: 335.7 344.3 349.9 353.3 357.2 5481.9 5629.7 5633.9 5673.9 5680.7 5545.2 5552.6 5553.0 5606.2 5625.3 4010.0 4334.6 4351.8 4355.9 4361.3 4271.7 4329.7 4335.2 4354.6 4379.5 4294.4 4302.5 4310.8 4317.6 4322.9 4305.1 4319.9 4325.0 4336.5 4353.5 3618.6 3803.6 3831.6 3833.1 3840.8 5337.3 5365.1 5402.5 5420.1 5420.2 4935.3 4938.4 4942.7 4981.9 4988.3 5729.4 5863.9 5904.7 5926.8 5934.6 4147.2 4184.2 4188.2 4208.8 4219.2 5883.9 6110.6 6136.6 6199.3 6216.2 4267.9 4289.9 4292.4 4298.5 4301.9 4348.7 4360.7 4364.2 4369.7 4379.7 4944.2 4948.9 4975.8 4985.7 5002.3 5769.6 5933.9 5968.6 5976.5 6002.7 1534.6 1550.4 1552.4 1554.5 1563.5 1012.3 1014.9 1020.5 1050.0 1066.6 5675.6 5853.9 5933.0 5936.3 6242.2 6110.2 6138.2 6159.1 6185.3 6203.8 5656.3 5855.7 5869.5 5871.2 5875.8 3482.5 3492.2 3510.3 3515.8 3520.4 6098.2 6898.1 6913.5 6929.6 6977.6 6276.3 6280.2 6307.8 6351.6 6371.2 6424.9 6507.5 6515.3 6516.9 6560.3 5976.4 6113.5 6152.3 6158.9 6174.5 6093.9 6115.8 6131.7 6143.5 6156.1 6233.6 6455.5 6486.3 6493.7 6524.4 4241.1 4248.5 4253.6 4266.4 4283.3 6120.3 6122.2 6136.7 6140.3 6146.8 5756.9 5889.6 6006.2 6009.2 6015.2 5963.5 5999.5 6000.7 6005.6 6008.4 5674.3 5741.3 5806.5 5895.0 5920.3 5976.8 5984.3 6019.8 6022.8 6027.4 5839.6 5844.6 5851.4 5861.2 5919.9 18:48:10 INFO - PROCESS | 6905 | 18:48:10 INFO - PROCESS | 6905 | [#5] yui.html Cycles:180 Average:16082.35 Median:4644.15 stddev:19158.10 (412.5%) stddev-sans-first:19177.34 18:48:10 INFO - PROCESS | 6905 | Values: 769.6 782.0 830.3 892.7 913.1 14704.0 15321.5 15342.4 15346.2 15354.6 11921.9 13831.7 13922.8 13943.0 13969.0 839.6 881.5 884.9 888.9 891.7 876.3 877.4 883.3 885.1 885.1 925.5 932.5 933.8 934.6 935.7 923.0 923.4 923.6 926.6 927.5 678.0 681.7 683.1 683.2 683.5 14842.3 15187.4 15217.9 15252.0 15272.1 14481.8 14678.8 14893.1 14894.3 15179.3 12221.2 12329.7 12344.4 12404.1 12550.7 4016.2 4039.4 4096.2 4109.9 4154.3 52145.2 53112.2 53219.7 53579.7 54400.2 11516.3 11596.9 11694.2 11705.2 11872.7 20846.6 20852.5 20895.2 21152.7 21164.2 8760.6 8813.8 8823.2 9059.4 9185.0 4901.5 4979.8 5047.0 5065.2 5122.3 3523.1 3603.8 3609.3 3612.0 3615.1 621.6 631.6 669.7 690.6 693.0 664.0 724.6 739.7 744.5 752.3 9859.4 10158.8 10180.8 10200.8 10202.5 626.9 662.8 773.1 782.4 804.4 4427.3 4606.9 4681.4 4699.1 4817.7 72120.6 73330.6 73359.6 73441.2 73670.6 60966.4 62565.4 62954.0 63259.1 65520.0 56309.4 56853.0 56967.1 57113.1 57934.4 8596.4 9010.7 9046.3 9050.1 9065.3 37338.8 37722.4 37779.8 37899.4 38457.0 47046.0 49661.0 51215.0 51739.2 52148.7 11762.4 12098.0 12684.2 12832.4 13145.9 13275.3 13325.4 13659.6 13695.2 14440.1 13248.4 13254.8 13485.5 13728.9 14000.0 12722.5 12902.2 13067.4 13458.1 14399.7 12409.1 12660.9 12900.8 13364.6 13708.5 10919.2 10932.0 11052.7 11144.2 11371.0 10535.1 10742.9 10758.8 10764.5 10842.0 18:48:10 INFO - PROCESS | 6905 | -------- Summary: end -------- 18:48:10 INFO - PROCESS | 6905 | 18:48:11 INFO - TEST-INFO | 6905: exit 0 18:48:11 INFO - TEST-OK | dromaeo_css | took 1109758ms 18:48:11 INFO - TEST-START | kraken 18:48:11 INFO - operating with platform_type : linux_ 18:48:11 INFO - Initialising browser for kraken test... 18:48:11 INFO - TEST-INFO | started process 7144 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmperFsRV/profile http://localhost:56278/getInfo.html) 18:48:15 INFO - PROCESS | 7144 | 18:48:15 INFO - PROCESS | 7144 | (process:7226): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:48:16 INFO - PROCESS | 7144 | __metrics Screen width/height:1600/1200 18:48:16 INFO - PROCESS | 7144 | colorDepth:24 18:48:16 INFO - PROCESS | 7144 | Browser inner width/height: 1024/697 18:48:16 INFO - PROCESS | 7144 | __metrics 18:48:16 ERROR - PROCESS | 7144 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 18:48:17 INFO - TEST-INFO | 7144: exit 0 18:48:17 INFO - Browser initialized. 18:48:17 INFO - Running cycle 1/1 for kraken test... 18:48:17 INFO - TEST-INFO | started process 7277 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmperFsRV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:19 INFO - PROCESS | 7277 | 18:48:19 INFO - PROCESS | 7277 | (process:7349): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:48:29 INFO - PROCESS | 7277 | RSS: Main: 152440832 18:48:29 INFO - PROCESS | 7277 | 18:48:31 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56278/tests/kraken/driver.html?testName=audio-beat-detection) 18:48:32 INFO - PROCESS | 7277 | RSS: Main: 156954624 18:48:32 INFO - PROCESS | 7277 | 18:48:35 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56278/tests/kraken/driver.html?testName=audio-dft) 18:48:36 INFO - PROCESS | 7277 | RSS: Main: 157794304 18:48:36 INFO - PROCESS | 7277 | 18:48:38 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56278/tests/kraken/driver.html?testName=audio-fft) 18:48:39 INFO - PROCESS | 7277 | RSS: Main: 157540352 18:48:39 INFO - PROCESS | 7277 | 18:48:41 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56278/tests/kraken/driver.html?testName=audio-oscillator) 18:48:42 INFO - PROCESS | 7277 | RSS: Main: 157749248 18:48:42 INFO - PROCESS | 7277 | 18:48:44 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:48:45 INFO - PROCESS | 7277 | RSS: Main: 157708288 18:48:45 INFO - PROCESS | 7277 | 18:48:48 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56278/tests/kraken/driver.html?testName=imaging-darkroom) 18:48:48 INFO - PROCESS | 7277 | RSS: Main: 158126080 18:48:48 INFO - PROCESS | 7277 | 18:48:52 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56278/tests/kraken/driver.html?testName=imaging-desaturate) 18:48:52 INFO - PROCESS | 7277 | RSS: Main: 157757440 18:48:52 INFO - PROCESS | 7277 | 18:48:55 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56278/tests/kraken/driver.html?testName=json-parse-financial) 18:48:56 INFO - PROCESS | 7277 | RSS: Main: 157294592 18:48:56 INFO - PROCESS | 7277 | 18:48:57 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:48:58 INFO - PROCESS | 7277 | RSS: Main: 157384704 18:48:58 INFO - PROCESS | 7277 | 18:49:00 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:00 INFO - PROCESS | 7277 | RSS: Main: 157802496 18:49:00 INFO - PROCESS | 7277 | 18:49:02 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:03 INFO - PROCESS | 7277 | RSS: Main: 157405184 18:49:03 INFO - PROCESS | 7277 | 18:49:05 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:49:06 INFO - PROCESS | 7277 | RSS: Main: 158044160 18:49:06 INFO - PROCESS | 7277 | 18:49:09 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:49:09 INFO - PROCESS | 7277 | RSS: Main: 157962240 18:49:09 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | Cycle 1(1): loaded http://localhost:56278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56278/tests/kraken/driver.html?testName=ai-astar) 18:49:11 INFO - PROCESS | 7277 | RSS: Main: 159268864 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | __start_tp_report 18:49:11 INFO - PROCESS | 7277 | _x_x_mozilla_page_load 18:49:11 INFO - PROCESS | 7277 | _x_x_mozilla_page_load_details 18:49:11 INFO - PROCESS | 7277 | |i|pagename|runs| 18:49:11 INFO - PROCESS | 7277 | |0;ai-astar;111;106;112;110;111;121;113;113;110;109 18:49:11 INFO - PROCESS | 7277 | |1;audio-beat-detection;154;133;156;172;128;158;154;134;182;128 18:49:11 INFO - PROCESS | 7277 | |2;audio-dft;163;163;162;162;162;164;165;162;168;164 18:49:11 INFO - PROCESS | 7277 | |3;audio-fft;76;77;74;173;78;76;77;76;174;76 18:49:11 INFO - PROCESS | 7277 | |4;audio-oscillator;92;93;160;93;92;92;144;92;93;152 18:49:11 INFO - PROCESS | 7277 | |5;imaging-gaussian-blur;118;117;117;117;118;117;117;117;117;118 18:49:11 INFO - PROCESS | 7277 | |6;imaging-darkroom;146;147;147;147;148;148;148;148;147;146 18:49:11 INFO - PROCESS | 7277 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;95 18:49:11 INFO - PROCESS | 7277 | |8;json-parse-financial;58;58;64;59;60;61;58;74;57;60 18:49:11 INFO - PROCESS | 7277 | |9;json-stringify-tinderbox;56;55;56;57;57;57;56;56;57;58 18:49:11 INFO - PROCESS | 7277 | |10;stanford-crypto-aes;73;74;72;74;83;74;75;75;74;74 18:49:11 INFO - PROCESS | 7277 | |11;stanford-crypto-ccm;127;117;118;118;119;120;126;119;195;120 18:49:11 INFO - PROCESS | 7277 | |12;stanford-crypto-pbkdf2;167;161;160;163;161;157;159;158;160;162 18:49:11 INFO - PROCESS | 7277 | |13;stanford-crypto-sha256-iterative;72;67;68;66;68;70;73;66;67;66 18:49:11 INFO - PROCESS | 7277 | __end_tp_report 18:49:11 INFO - PROCESS | 7277 | __start_cc_report 18:49:11 INFO - PROCESS | 7277 | _x_x_mozilla_cycle_collect,841 18:49:11 INFO - PROCESS | 7277 | __end_cc_report 18:49:11 INFO - PROCESS | 7277 | __startTimestamp1461635351821__endTimestamp 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | ------- Summary: start ------- 18:49:11 INFO - PROCESS | 7277 | Number of tests: 14 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.50 stddev:3.89 (3.5%) stddev-sans-first:4.12 18:49:11 INFO - PROCESS | 7277 | Values: 111.0 106.0 112.0 110.0 111.0 121.0 113.0 113.0 110.0 109.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:155.00 stddev:18.72 (12.1%) stddev-sans-first:19.79 18:49:11 INFO - PROCESS | 7277 | Values: 154.0 133.0 156.0 172.0 128.0 158.0 154.0 134.0 182.0 128.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:1.90 (1.2%) stddev-sans-first:2.01 18:49:11 INFO - PROCESS | 7277 | Values: 163.0 163.0 162.0 162.0 162.0 164.0 165.0 162.0 168.0 164.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#3] audio-fft Cycles:10 Average:95.70 Median:76.00 stddev:41.02 (54.0%) stddev-sans-first:42.88 18:49:11 INFO - PROCESS | 7277 | Values: 76.0 77.0 74.0 173.0 78.0 76.0 77.0 76.0 174.0 76.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#4] audio-oscillator Cycles:10 Average:110.30 Median:92.00 stddev:29.03 (31.5%) stddev-sans-first:30.02 18:49:11 INFO - PROCESS | 7277 | Values: 92.0 93.0 160.0 93.0 92.0 92.0 144.0 92.0 93.0 152.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:49:11 INFO - PROCESS | 7277 | Values: 118.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.50 stddev:0.79 (0.5%) stddev-sans-first:0.71 18:49:11 INFO - PROCESS | 7277 | Values: 146.0 147.0 147.0 147.0 148.0 148.0 148.0 148.0 147.0 146.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:49:11 INFO - PROCESS | 7277 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:60.00 stddev:5.02 (8.4%) stddev-sans-first:5.21 18:49:11 INFO - PROCESS | 7277 | Values: 58.0 58.0 64.0 59.0 60.0 61.0 58.0 74.0 57.0 60.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:57.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 18:49:11 INFO - PROCESS | 7277 | Values: 56.0 55.0 56.0 57.0 57.0 57.0 56.0 56.0 57.0 58.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.00 stddev:3.01 (4.1%) stddev-sans-first:3.12 18:49:11 INFO - PROCESS | 7277 | Values: 73.0 74.0 72.0 74.0 83.0 74.0 75.0 75.0 74.0 74.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:120.00 stddev:23.82 (19.8%) stddev-sans-first:25.26 18:49:11 INFO - PROCESS | 7277 | Values: 127.0 117.0 118.0 118.0 119.0 120.0 126.0 119.0 195.0 120.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.80 Median:161.00 stddev:2.82 (1.8%) stddev-sans-first:1.90 18:49:11 INFO - PROCESS | 7277 | Values: 167.0 161.0 160.0 163.0 161.0 157.0 159.0 158.0 160.0 162.0 18:49:11 INFO - PROCESS | 7277 | 18:49:11 INFO - PROCESS | 7277 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:2.54 (3.7%) stddev-sans-first:2.32 18:49:11 INFO - PROCESS | 7277 | Values: 72.0 67.0 68.0 66.0 68.0 70.0 73.0 66.0 67.0 66.0 18:49:11 INFO - PROCESS | 7277 | -------- Summary: end -------- 18:49:11 INFO - PROCESS | 7277 | 18:49:12 INFO - TEST-INFO | 7277: exit 0 18:49:12 INFO - TEST-OK | kraken | took 61078ms 18:49:12 INFO - SUITE-END | took 1170s 18:49:12 INFO - Completed test suite (00:19:31) 18:49:12 INFO - javascript benchmark 18:49:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.554290170827, 3377.628460869304, 3390.0507857799826, 3398.0655319787056, 3400.2859399526096, 5519.0, 5558.027345214587, 5577.69322687252, 5589.329280486337, 5617.8033768818095, 5535.225068490405, 5579.9163012554845, 5598.720063996804, 5613.242212301338, 5623.775048998045, 5042.848714538561, 5120.718360490171, 5137.126688462951, 5165.948340516603, 5178.5339319461245, 5077.619178561623, 5193.324867767185, 5226.8693282667855, 5264.710440925747, 5286.04851126797, 5024.296598476216, 5108.182690769478, 5119.257707632391, 5193.221016847466, 5197.688138711689, 5072.289879416885, 5105.591552675795, 5111.182210846284, 5127.641065125443, 5136.5890728741615, 4975.129352363322, 5012.874678133039, 5070.29015937769, 5076.289319495274, 5105.081085404626, 5282.181261904411, 5409.783608655649, 5416.350037995466, 5460.180972854064, 5569.916451253234, 5965.164876917192, 6005.909911351333, 6006.699665016732, 6008.218931538916, 6009.0, 5994.8501287467725, 6056.42463965927, 6073.423024812636, 6074.9392506075255, 6085.513158947274, 5401.513863752242, 5490.835274941718, 5585.860353491195, 5605.607607467479, 5643.943560564383, 6061.424164704346, 6070.453659170696, 6077.149199112039, 6124.846878828065, 6133.294671112879, 5834.620749651259, 5873.089671100986, 5887.4406931341455, 5903.085021821625, 5908.497777688945, 5785.450382213683, 5815.88368232633, 5869.090291004901, 5895.115732640138, 5910.497607703307, 5860.560457965669, 5914.970425147847, 5917.142014407887, 5920.733566989496, 5928.229330187048, 5837.532997360201, 5868.119782032729, 5906.852328691731, 5928.081147422156, 5938.762449502058, 4550.294704320836, 4594.609458196091, 4596.195665758479, 4608.147492713859, 4625.629949604024, 4016.377461493473, 4027.0536423940353, 4046.1503084352616, 4052.3313653247474, 4055.270051390778, 5585.748641311151, 5586.0, 5594.049011668027, 5645.576581756384, 5666.065099258659, 5919.585629006015, 5948.881022379528, 5987.610805297729, 6040.818775436744, 6122.112293717386, 5764.5676574257095, 5776.0, 5781.971090144607, 5789.45000224978, 5795.13073039039, 5024.698518088926, 5118.59051275897, 5183.377994640668, 5191.0, 5205.297284866495, 6246.156768836149, 6304.432601065926, 6337.6197428154455, 6345.0, 6345.0482427636225, 5974.372690867396, 6031.306399764083, 6037.818865434044, 6040.305364883095, 6057.42454466825, 6127.724252408563, 6169.352218017044, 6255.155554000152, 6277.623342599459, 6282.591631543935, 5898.469137777621, 5899.970500147643, 5907.527397808251, 5909.70451477433, 5922.378150294329, 5987.700614969322, 6007.0, 6016.548758843017, 6018.939810601838, 6019.518438524995, 5648.0, 5706.714664266853, 5754.395788442321, 5776.393478684678, 5846.181534585076, 5755.625884317506, 5761.905238004766, 5801.3038435388025, 5810.622309549865, 5823.912641310299, 5647.915281270702, 5671.659700417823, 5714.314282286152, 5715.371309156073, 5730.312362516524, 5709.22925405065, 5772.913406298993, 5849.356570777296, 5926.022206335906, 5933.821985340447, 5584.46947539983, 5620.04459241938, 5629.437056294338, 5631.0, 5646.576506762091], "value": 5511.281778624874, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.4017804065361, 681.9386255237027, 683.2245401469331, 688.077975512813, 709.5707097206193, 42729.14541709164, 44773.0, 44835.77582112089, 44844.551554484446, 44911.0, 45212.547874521326, 46078.539214607845, 46195.0, 46249.30626040612, 46366.30450543245, 8801.691940782073, 9032.638694452247, 9049.140331668481, 9062.275017998578, 9081.95459022704, 8878.0, 8935.597898094602, 8947.194752472276, 9041.412308199979, 9055.320850936174, 8941.821163576757, 9028.32287578431, 9052.773680658003, 9055.637774489012, 9074.410163339362, 8990.37067405282, 9000.459972401644, 9065.410748301372, 9068.501232432214, 9073.63705451781, 8924.107589241024, 9039.864402033974, 9048.049954754788, 9057.954710226473, 9058.048904865025, 42111.0, 42293.0, 42566.78716606428, 42615.14769704588, 42960.14079718388, 3612.945805812915, 4301.763403012833, 4350.350618607332, 4396.670249731283, 4459.643228541741, 30783.538246926313, 31150.0, 33134.0, 34051.48922766161, 35729.28541429157, 4211.642010429117, 4229.069604686964, 4234.85178018771, 4256.212600668886, 4325.740455572675, 347312.0, 347807.0, 348794.0, 349150.0, 350362.0, 36484.81757591248, 37085.81457092751, 37118.257634847694, 64230.35769642337, 64904.0, 38234.0, 38379.2324153521, 38408.42387364192, 38495.80752096222, 38516.0, 15316.081035137928, 15465.0, 15538.456154034555, 15586.9220653896, 15602.609934751496, 12715.491380344867, 12837.614871553867, 12851.807222891664, 12852.614421567181, 12880.742385152433, 9576.856347154799, 9601.279904007228, 9626.326157169071, 9688.612455501701, 9717.271204659679, 3648.051506608248, 3726.6832319252626, 3740.401535754266, 3740.981295093507, 3777.225668737902, 1043.8799538053222, 1080.4543705428866, 1094.8303013032992, 1120.4453795371344, 1194.0149376764205, 33032.0, 33424.83287583595, 33914.152146196546, 34122.48816267758, 34381.48427773586, 1171.625079974417, 1222.5293262094158, 1231.6551365617463, 1235.6478403654914, 1242.5278394210143, 17654.646907061786, 17923.820761792475, 18003.909980450015, 18221.0, 18230.544236394195, 353910.0, 420067.0, 424021.0, 424060.0, 508859.0, 64125.67937160284, 64737.0, 64942.0, 65071.34928650653, 65183.0, 1483.0117313613077, 1510.3807438949977, 1511.2821409830462, 1511.7052174826024, 1528.3580896023698, 33512.83243583864, 34242.31515369729, 34257.82871085629, 34258.48612270768, 34538.481922770676, 54816.17775733287, 55613.0, 55723.0, 55724.44275557192, 55781.721091395906, 453842.0, 463178.0, 464335.0, 467073.0, 467491.0, 31525.0, 31729.524057139624, 31879.6812031886, 31899.521507176945, 31969.520457193626, 49528.75235623943, 51066.701998409415, 51294.74352628213, 51360.22959655683, 51552.0, 41967.1606567873, 42276.78861605672, 43111.784441077594, 43154.56845431506, 43918.780406097765, 42002.36996444994, 42505.36241956435, 42883.14233715371, 43497.56502435062, 43560.0, 42957.14085718331, 43293.35059974166, 43845.78077109594, 44115.0, 44628.77685611551, 37461.43807842939, 37852.81073594614, 38180.0, 38357.04107397337, 38666.80666596762, 28495.145145645663, 28733.712662873102, 28822.567661484674, 28870.42259154847, 29111.0], "value": 19724.945842793033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.2316753012108, 429.45459266731234, 432.48102277267276, 434.2314104035856, 437.3942090205065, 19189.904050479745, 19258.90370548147, 19864.70202946955, 20700.89649551754, 20706.482337941557, 17676.204570794314, 18217.726734099, 19338.709919351222, 19390.612187756236, 19542.902285488588, 4800.207965685667, 5192.247124167013, 5203.063448579254, 5209.86975325618, 5213.608979326546, 5096.4648711885275, 5141.1003074462005, 5173.508516690908, 5174.275601415805, 5177.508136727024, 4945.0, 5255.973720131413, 5256.8685782855355, 5257.316548848664, 5272.0, 5162.896742065175, 5217.817376391827, 5220.243064755608, 5235.6596821206695, 5266.4733526647415, 5319.601029922771, 5347.652402593819, 5366.302380690524, 5368.114261146906, 5370.758315875795, 13375.26436046017, 13544.0, 18301.0, 23715.525689486112, 23900.0, 2246.2587346175724, 2325.6511523271474, 2347.342744031657, 2378.1557547070715, 2380.702412198475, 9101.815564264594, 9571.569279382444, 13526.526571569948, 15545.145017024059, 15659.921700391538, 1939.8933058681766, 2066.0, 2093.979060209409, 2111.8944052797297, 2149.344449942767, 82144.0, 84624.0, 84726.15273847245, 86334.56832715796, 87005.99999999936, 10935.56257749681, 11127.888721112686, 11363.602273920373, 11391.658250252505, 11462.140339474572, 15758.527244182693, 15760.92119539395, 15790.763138552931, 15858.207089645703, 15920.0, 7913.9604301978125, 8058.637361318754, 8091.190880911861, 8241.752747417588, 8251.133630968661, 7565.356944659656, 7676.57778822159, 7680.270374314431, 7689.846203075907, 7734.110577283572, 4794.376731024944, 4822.54050434281, 4842.273658951185, 4890.437599676012, 4936.284238785428, 1835.6971099768389, 1996.8103030212108, 2041.3671761753903, 2094.1728017433334, 2095.256184054669, 976.1263669016191, 1018.0, 1048.9737756555994, 1123.1913022623698, 1136.5510623303749, 19157.521061973563, 19957.414342490458, 20323.491912702004, 20349.186032558533, 20593.79406205949, 1032.7986042721598, 1072.4316112460408, 1094.6497120921388, 1118.3290025984345, 1120.624590762104, 10458.111060559786, 10858.968561959331, 10866.873834679925, 10868.014861731073, 10916.009948161662, 593432.0, 613822.0, 617453.0, 620984.0, 626489.0, 87050.0, 91036.0, 91748.0, 92181.0, 92442.07557924469, 1104.8397982292522, 1107.950142243601, 1117.4915413486844, 1130.5534313945946, 1132.8640563132312, 17063.7440438396, 17090.72448187324, 17142.65714685674, 17151.57121071983, 17280.0, 38678.22643547057, 39173.41239881461, 39208.0, 39847.0, 39930.401043984944, 53997.190042150185, 55754.72122639361, 58790.0, 59186.0, 60331.0, 7992.960035199787, 8423.241908228043, 8550.74347769568, 8684.565771711515, 8801.867971980297, 9465.66870159541, 13564.253966031964, 13881.37533811001, 13918.443262269397, 14043.719125617226, 11592.47833847462, 12028.09789265791, 12177.147599667938, 12866.292353920624, 12887.09790314669, 11542.134339924707, 11867.525298987945, 11874.406279686154, 11930.22453540517, 12109.394530273627, 11592.6522204334, 11806.763864722485, 11938.044956403293, 12369.876301236873, 13237.205767653972, 8571.528565928933, 8780.692675756318, 8813.42712723671, 8829.470231786114, 8969.596368163579, 9121.0, 12049.33728644933, 12230.14388992762, 12417.0, 12588.30764307972], "value": 9323.635190886538, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1546.6751982083765, 1549.5893588199128, 1740.2951804519173, 1789.4810504953564, 1884.029724691783, 7478.887816682741, 7764.378849692025, 8002.959985200066, 8103.068147163078, 8136.6745330186795, 7430.517016393931, 7946.880796788053, 8054.0, 8135.1051384347675, 8149.551774652392, 2283.7830406111475, 2303.3780879162614, 2320.9419764505856, 2321.1759825262043, 2333.5449587330477, 2325.662778897059, 2349.6240601503764, 2359.1153317505937, 2359.2568340972625, 2370.5851475991717, 2311.179531266401, 2334.4747431827786, 2338.5206032763326, 2345.108858633717, 2357.7524359942217, 2239.787220214077, 2269.841111122222, 2284.451731584416, 2288.0275882749766, 2294.19703103914, 2232.586971410294, 2284.4403121235237, 2301.620232661609, 2303.9308820735237, 2304.68886700295, 8234.794130146734, 8271.1315311892, 8293.875591866126, 8348.206920342558, 8354.373421993314, 5017.34774479319, 5189.896202075975, 5197.194434862602, 5211.947880521184, 5221.765020574083, 3143.4184675835045, 3256.495243237302, 3360.2271477560053, 3361.5293858859777, 3368.2758206985372, 1182.3497076607916, 1191.1780871198846, 1209.8003829368145, 1219.4024927785322, 1231.445849367788, 11233.887661123366, 11299.717507062307, 11302.830457543143, 11311.321320720865, 11316.886831131666, 4492.9550704493195, 4505.774711264424, 4513.638908887281, 4605.723656580616, 4629.213033784265, 9699.272554558436, 9703.466309353053, 9703.563339649732, 9715.611375544899, 9719.85420218683, 5019.519241823671, 5034.723090229999, 5043.798248070036, 5058.266551350107, 5060.671056381323, 4098.262312783728, 4562.429696287964, 4600.42494688164, 4672.415948006499, 4692.0, 4100.1184745279315, 4120.75275483472, 4132.504099508078, 4155.667546596265, 4188.706790524634, 1338.4110991163857, 1350.8649135086412, 1357.9185248885099, 1361.6051345109806, 1394.1704685711986, 891.4428482198626, 920.5259291464901, 1058.8041212375736, 1059.4120263253967, 1079.9028087472166, 5764.74058667361, 5812.912806307909, 5880.176775251468, 5922.230110085748, 5946.940530594725, 457.2295681776218, 457.8809509527547, 460.470458972178, 1065.190455253997, 1097.7200813792592, 4077.4903137107863, 4085.6935729820384, 4091.3044782387065, 4140.0891803803115, 4152.730072545275, 9566.282528810367, 9936.254780891462, 9994.550245238981, 10006.749831254278, 10025.448600326907, 9699.806003879883, 9700.466474343979, 9778.804423911482, 9859.605615775288, 9890.604375824887, 7763.146053934062, 7945.761627151079, 7951.363890888715, 8011.319037881845, 8017.118117007125, 5677.772889084556, 5758.62568933018, 5810.310452862378, 5966.522678185648, 5981.760729570942, 9359.532023398939, 9448.574814133242, 9489.66786162481, 9518.000609936134, 9577.281703872377, 9161.633534658538, 9434.433933963985, 9479.099485548863, 9483.905160948303, 9522.047795220422, 6829.93170068293, 7187.856242875008, 7348.632568371667, 7463.253674632493, 7525.435592330707, 8015.839683206186, 8034.276915077671, 8037.0, 8119.3098586618435, 8139.389545784208, 7105.0, 7309.671064801991, 7329.963350183429, 7338.816529586803, 7452.925470745223, 7122.821929451805, 7165.176004759311, 7177.928220717935, 7194.280571942973, 7253.0, 6895.482838787011, 6912.827179320558, 6935.098437203132, 6946.826329341807, 6957.8956315654295, 4927.531884470969, 4936.963237720119, 5000.824971125993, 5049.293098966221, 5155.097857874932, 4947.529984651453, 4990.700557966534, 5008.073506401327, 5058.317127187783, 5076.466970967996], "value": 4482.5804869747735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.6811029521955, 344.26842958712734, 349.88978471781377, 353.3039911374591, 357.1660173494889, 5481.862953426162, 5629.662220266787, 5633.859153521164, 5673.886522269552, 5680.693440508686, 5545.170093869014, 5552.611317207797, 5552.972235138819, 5606.243157173781, 5625.324961004675, 4009.9799501002453, 4334.5882141196535, 4351.825926962925, 4355.934660980088, 4361.280388735855, 4271.722338048033, 4329.653627709791, 4335.176316499871, 4354.608085272325, 4379.4963579188325, 4294.398784170218, 4302.48370195577, 4310.784460776964, 4317.633001194887, 4322.913541729164, 4305.117450922569, 4319.913601727979, 4324.96200911782, 4336.522982471925, 4353.455818022747, 3618.6200448952745, 3803.6497043549693, 3831.5593706723525, 3833.1375440525658, 3840.8079596020366, 5337.3061502004875, 5365.141577347645, 5402.486763757437, 5420.13277875538, 5420.186971954199, 4935.259711043336, 4938.3580134582635, 4942.678725882806, 4981.850544483671, 4988.251762235657, 5729.398413166641, 5863.941360586382, 5904.675242861684, 5926.7925622603, 5934.554908381845, 4147.170565886805, 4184.205001049791, 4188.225178342016, 4208.842568293713, 4219.198352313051, 5883.881542782289, 6110.572259941806, 6136.570440069197, 6199.318075011745, 6216.160818289518, 4267.893302667459, 4289.892752681208, 4292.420523229324, 4298.527162012176, 4301.91396172081, 4348.7390756554705, 4360.738355698605, 4364.214441400515, 4369.737815731066, 4379.737215767064, 4944.159492886218, 4948.851534453972, 4975.751212439363, 4985.675931064542, 5002.299678045077, 5769.567282453832, 5933.881322373529, 5968.552358573038, 5976.491998180117, 6002.7298771554515, 1534.562649644868, 1550.4418409372618, 1552.4256025270722, 1554.4947891935167, 1563.5074951390502, 1012.3419777144915, 1014.9238807089498, 1020.5203554329453, 1049.9790004199874, 1066.6106870992032, 5675.602707810413, 5853.912191317134, 5932.970335148383, 5936.347001829795, 6242.219722534684, 6110.205673262448, 6138.158552434252, 6159.076138579249, 6185.257769067741, 6203.751849926042, 5656.264685590929, 5855.707214639251, 5869.501092407193, 5871.236739223874, 5875.794347197828, 3482.5472688550326, 3492.161881148506, 3510.3330367230146, 3515.789052656849, 3520.4015317395592, 6098.237720284965, 6898.1377327834025, 6913.481488888354, 6929.5842249465195, 6977.581345119309, 6276.340984196685, 6280.214973128359, 6307.779227727007, 6351.555391122577, 6371.171747672865, 6424.871502569922, 6507.479401647857, 6515.250746164157, 6516.934830651633, 6560.278369379364, 5976.432238937294, 6113.480354169946, 6152.323244443197, 6158.907616385847, 6174.536909731698, 6093.878122437615, 6115.785947491817, 6131.724072416663, 6143.539234557516, 6156.1381406604005, 6233.625982441068, 6455.4512866406885, 6486.318936511599, 6493.675316234264, 6524.4454221389815, 4241.109367032957, 4248.511421186603, 4253.617174454314, 4266.418816616027, 4283.293256612624, 6120.265568131853, 6122.234720659918, 6136.662483563447, 6140.3245642978345, 6146.78486252979, 5756.913646295226, 5889.617174883619, 6006.189164462917, 6009.158717779427, 6015.248093988252, 5963.522918166623, 5999.490298175616, 6000.69996500182, 6005.579609427298, 6008.399160083956, 5674.2623458950075, 5741.25363702716, 5806.531481343897, 5894.970525147518, 5920.3062606514795, 5976.790812321722, 5984.281886173687, 6019.819405417845, 6022.759089636541, 6027.427394397525, 5839.591228613956, 5844.620099693507, 5851.356350801323, 5861.179434879035, 5919.881602368015], "value": 4548.912371857408, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.5844244108181, 782.0380931454315, 830.3092752714184, 892.7232557907049, 913.0549880873295, 14704.0, 15321.540353789404, 15342.38630454782, 15346.232688365564, 15354.616134596641, 11921.880781192185, 13831.654208644788, 13922.791158132635, 13943.0, 13969.0, 839.5634270179503, 881.5415983688478, 884.8672699095155, 888.9377743557953, 891.6700820696351, 876.2814492116468, 877.3814460805118, 883.2713011765287, 885.0662551008684, 885.1325700813206, 925.4771054354302, 932.4638332958576, 933.7525555727738, 934.6308208257734, 935.7239614313794, 923.044648788504, 923.4413180026122, 923.6166990698852, 926.5552534783275, 927.4898805656895, 677.9932200677979, 681.686424244848, 683.0880774166444, 683.2074793239818, 683.5454422808876, 14842.332095055748, 15187.392504299814, 15217.923910380488, 15252.0, 15272.083674979536, 14481.78277325841, 14678.779818302733, 14893.106413615218, 14894.329755161043, 15179.316930738143, 12221.237667628691, 12329.69175770604, 12344.444499997522, 12404.13171078025, 12550.68623284416, 4016.15660711251, 4039.3738970459626, 4096.221717873595, 4109.856155034589, 4154.335306350999, 52145.2178217327, 53112.203316950276, 53219.733901330634, 53579.732101339636, 54400.18399723968, 11516.30902145874, 11596.88403115958, 11694.239874408306, 11705.180637355303, 11872.703182420508, 20846.583068338547, 20852.478688032617, 20895.164193432094, 21152.682709759367, 21164.153433862783, 8760.649574017096, 8813.823723525493, 8823.161799629024, 9059.36584439096, 9185.0, 4901.460839307673, 4979.825706100341, 5046.9747651261505, 5065.164247899129, 5122.334096567422, 3523.066387407339, 3603.8198090095916, 3609.296187243462, 3612.0, 3615.1142969972525, 621.6114928169894, 631.5768435148461, 669.7388018672722, 690.6477696374885, 692.9930700693029, 663.9502037347219, 724.5695393737192, 739.7263012685233, 744.508624307965, 752.2665401233753, 9859.35914165577, 10158.847617285746, 10180.847287290548, 10200.846987295197, 10202.540885660163, 626.8558231606756, 662.7580932959435, 773.0739152308486, 782.4483738964017, 804.3685706720197, 4427.285194681254, 4606.884827879263, 4681.414823147106, 4699.107169637758, 4817.686850354786, 72120.63939680373, 73330.63334683292, 73359.63320183472, 73441.16397090115, 73670.63164684249, 60966.390336096956, 62565.37434625687, 62954.0, 63259.05111423332, 65520.0, 56309.43690563042, 56853.0, 56967.14549281764, 57113.14330285049, 57934.42065579374, 8596.355273354398, 9010.684626038057, 9046.321525885716, 9050.140236677506, 9065.274778017874, 37338.8133059333, 37722.43416348702, 37779.81110094542, 37899.43150852684, 38457.0, 47046.0, 49661.0, 51215.0, 51739.223911640605, 52148.739256304994, 11762.411879405825, 12097.971672407944, 12684.23894566329, 12832.358382080673, 13145.934270328587, 13275.336233188109, 13325.400356984153, 13659.590212293648, 13695.246761428218, 14440.133591984515, 13248.403821828233, 13254.801177982145, 13485.528006519724, 13728.93135534316, 14000.0, 12722.491100355883, 12902.225866447669, 13067.411966461343, 13458.125221860548, 14399.712005760035, 12409.131360804657, 12660.936695316464, 12900.806487902877, 13364.599062027766, 13708.451661933412, 10919.235653504178, 10932.0, 11052.723681908017, 11144.164187685794, 11371.0, 10535.104516115909, 10742.946285268523, 10758.8386174209, 10764.515596798006, 10842.024217820433], "value": 7181.05381135273, "unit": "score"}], "name": "dromaeo_css", "value": 7276.456624938419, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 110.0, 111.0, 121.0, 113.0, 113.0, 110.0, 109.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 133.0, 156.0, 172.0, 128.0, 158.0, 154.0, 134.0, 182.0, 128.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 162.0, 164.0, 165.0, 162.0, 168.0, 164.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 74.0, 173.0, 78.0, 76.0, 77.0, 76.0, 174.0, 76.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 160.0, 93.0, 92.0, 92.0, 144.0, 92.0, 93.0, 152.0], "value": 110.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 148.0, 148.0, 148.0, 148.0, 147.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 64.0, 59.0, 60.0, 61.0, 58.0, 74.0, 57.0, 60.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 57.0, 57.0, 57.0, 56.0, 56.0, 57.0, 58.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 74.0, 83.0, 74.0, 75.0, 75.0, 74.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 117.0, 118.0, 118.0, 119.0, 120.0, 126.0, 119.0, 195.0, 120.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 161.0, 160.0, 163.0, 161.0, 157.0, 159.0, 158.0, 160.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 68.0, 66.0, 68.0, 70.0, 73.0, 66.0, 67.0, 66.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1539.9, "alertThreshold": 2.0}]} 18:49:13 INFO - Return code: 0 18:49:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:49:13 ERROR - # TBPL SUCCESS # 18:49:13 INFO - Running post-action listener: _resource_record_post_action 18:49:13 INFO - Running post-run listener: _resource_record_post_run 18:49:14 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 498847744; Read time: 312; Write time: 547408 18:49:14 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 4096; Write bytes: 248762368; Read time: 116; Write time: 397584 18:49:14 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250085376; Read time: 196; Write time: 149824 18:49:14 INFO - Running post-run listener: _upload_blobber_files 18:49:14 INFO - Blob upload gear active. 18:49:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:49:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:49:14 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:49:14 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:49:14 INFO - (blobuploader) - INFO - Open directory for files ... 18:49:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:49:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:49:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:49:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:49:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:49:15 INFO - (blobuploader) - INFO - Done attempting. 18:49:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:49:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:49:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:49:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:49:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:49:16 INFO - (blobuploader) - INFO - Done attempting. 18:49:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:49:16 INFO - Return code: 0 18:49:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:49:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:49:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/336a5e56b904b358ae9031cbf2031d32251c18c203ac2a054a3c014267ca91c3d71e7a9253ad196961c4b3792262fd2d59b047d8361acd6a9e9f5008346dcc3f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef600d772160db1294f7c4864f7e716f415a9e0a976dff701baef8934aac967c1711f96cfd6d83899de2641a4c045717095eadb31c775482ef0079d0f41f11e9"} 18:49:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:49:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:49:16 INFO - Contents: 18:49:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/336a5e56b904b358ae9031cbf2031d32251c18c203ac2a054a3c014267ca91c3d71e7a9253ad196961c4b3792262fd2d59b047d8361acd6a9e9f5008346dcc3f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef600d772160db1294f7c4864f7e716f415a9e0a976dff701baef8934aac967c1711f96cfd6d83899de2641a4c045717095eadb31c775482ef0079d0f41f11e9"} 18:49:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:49:16 INFO - Copying logs to upload dir... 18:49:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:49:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.822559 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-25 18:49:15.989660) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 18:49:15.993458) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/336a5e56b904b358ae9031cbf2031d32251c18c203ac2a054a3c014267ca91c3d71e7a9253ad196961c4b3792262fd2d59b047d8361acd6a9e9f5008346dcc3f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef600d772160db1294f7c4864f7e716f415a9e0a976dff701baef8934aac967c1711f96cfd6d83899de2641a4c045717095eadb31c775482ef0079d0f41f11e9"} build_url:https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/Qg86yukiT7e7f22tNDYApg/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/336a5e56b904b358ae9031cbf2031d32251c18c203ac2a054a3c014267ca91c3d71e7a9253ad196961c4b3792262fd2d59b047d8361acd6a9e9f5008346dcc3f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef600d772160db1294f7c4864f7e716f415a9e0a976dff701baef8934aac967c1711f96cfd6d83899de2641a4c045717095eadb31c775482ef0079d0f41f11e9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 18:49:16.019449) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:49:16.019851) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1461634045.605147-291096840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004803 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:49:16.103263) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 18:49:16.103631) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 18:49:16.103979) ========= ========= Total master_lag: 0.17 =========