builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1461859304.64 results: success (0) buildid: 20160428073807 builduid: cf8b813ee5d746f9ac2dd336f9f9ce55 revision: 4292da9df16b220f30921100282264a34cd2ff03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.642178) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.642646) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.642938) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.762190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.762543) ========= 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=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-28 09:01:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-04-28 09:01:45 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089349 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.865259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.865577) ========= 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=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015835 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.915599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:44.915964) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4292da9df16b220f30921100282264a34cd2ff03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 4292da9df16b220f30921100282264a34cd2ff03 --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=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-28 09:01:45,254 truncating revision to first 12 chars 2016-04-28 09:01:45,254 Setting DEBUG logging. 2016-04-28 09:01:45,254 attempt 1/10 2016-04-28 09:01:45,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4292da9df16b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 09:01:45,502 unpacking tar archive at: mozilla-central-4292da9df16b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.532202 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:45.461819) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:45.462135) ========= script_repo_revision: 4292da9df16b220f30921100282264a34cd2ff03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:45.462565) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:45.463169) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:01:45.472976) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-28 09:01:45.473395) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:01:45 INFO - MultiFileLogger online at 20160428 09:01:45 in /builds/slave/test 09:01:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:01:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:45 INFO - {'append_to_log': False, 09:01:45 INFO - 'base_work_dir': '/builds/slave/test', 09:01:45 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:01:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:45 INFO - 'branch': 'Firefox-Non-PGO', 09:01:45 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:45 INFO - 'config_files': ('talos/linux_config.py',), 09:01:45 INFO - 'default_actions': ('clobber', 09:01:45 INFO - 'read-buildbot-config', 09:01:45 INFO - 'download-and-extract', 09:01:45 INFO - 'populate-webroot', 09:01:45 INFO - 'create-virtualenv', 09:01:45 INFO - 'install', 09:01:45 INFO - 'run-tests'), 09:01:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:45 INFO - 'download_minidump_stackwalk': True, 09:01:45 INFO - 'download_symbols': 'ondemand', 09:01:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:45 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:45 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:45 INFO - 'installer_path': 'installer.exe', 09:01:45 INFO - 'log_level': 'info', 09:01:45 INFO - 'log_name': 'talos', 09:01:45 INFO - 'log_to_console': True, 09:01:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:01:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:01:45 INFO - 'opt_config_files': (), 09:01:45 INFO - 'pip_index': False, 09:01:45 INFO - 'sps_profile': False, 09:01:45 INFO - 'sps_profile_interval': 0, 09:01:45 INFO - 'suite': 'dromaeojs-e10s', 09:01:45 INFO - 'system_bits': '32', 09:01:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:45 INFO - 'title': 'talos-linux64-ix-047', 09:01:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:45 INFO - 'use_talos_json': True, 09:01:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:45 INFO - 'work_dir': 'build'} 09:01:45 INFO - ##### 09:01:45 INFO - ##### Running clobber step. 09:01:45 INFO - ##### 09:01:45 INFO - Running pre-action listener: _resource_record_pre_action 09:01:45 INFO - Running main action method: clobber 09:01:45 INFO - rmtree: /builds/slave/test/build 09:01:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:46 INFO - Running post-action listener: _resource_record_post_action 09:01:46 INFO - ##### 09:01:46 INFO - ##### Running read-buildbot-config step. 09:01:46 INFO - ##### 09:01:46 INFO - Running pre-action listener: _resource_record_pre_action 09:01:46 INFO - Running main action method: read_buildbot_config 09:01:46 INFO - Using buildbot properties: 09:01:46 INFO - { 09:01:46 INFO - "project": "", 09:01:46 INFO - "product": "firefox", 09:01:46 INFO - "script_repo_revision": "production", 09:01:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:01:46 INFO - "repository": "", 09:01:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:01:46 INFO - "buildid": "20160428073807", 09:01:46 INFO - "pgo_build": "False", 09:01:46 INFO - "basedir": "/builds/slave/test", 09:01:46 INFO - "buildnumber": 339, 09:01:46 INFO - "slavename": "talos-linux64-ix-047", 09:01:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:01:46 INFO - "platform": "ubuntu64_hw", 09:01:46 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 09:01:46 INFO - "branch": "mozilla-central", 09:01:46 INFO - "repo_path": "mozilla-central", 09:01:46 INFO - "revision": "4292da9df16b220f30921100282264a34cd2ff03", 09:01:46 INFO - "stage_platform": "linux64", 09:01:46 INFO - "builduid": "cf8b813ee5d746f9ac2dd336f9f9ce55", 09:01:46 INFO - "slavebuilddir": "test" 09:01:46 INFO - } 09:01:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:01:46 INFO - Running post-action listener: _resource_record_post_action 09:01:46 INFO - ##### 09:01:46 INFO - ##### Running download-and-extract step. 09:01:46 INFO - ##### 09:01:46 INFO - Running pre-action listener: _resource_record_pre_action 09:01:46 INFO - Running main action method: download_and_extract 09:01:46 INFO - mkdir: /builds/slave/test/build/tests 09:01:46 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')]} 09:01:46 INFO - https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:01:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:01:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:01:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:01:48 INFO - Downloaded 1361 bytes. 09:01:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:01:48 INFO - Using the following test package requirements: 09:01:48 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:01:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:01:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:01:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'jsshell-linux-x86_64.zip'], 09:01:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:01:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:01:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:01:48 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:01:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:01:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:01:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:01:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:01: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')]} 09:01:48 INFO - https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:01:50 INFO - Downloaded 22300738 bytes. 09:01:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:01:51 INFO - Return code: 0 09:01:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:51 INFO - https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:01:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:01:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:01:55 INFO - Downloaded 22300738 bytes. 09:01:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:01:57 INFO - Return code: 0 09:01: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')]} 09:01:57 INFO - https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:01:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:01:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:01:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:01:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:01:59 INFO - Downloaded 10840145 bytes. 09:01:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:01:59 INFO - Return code: 0 09:01:59 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')]} 09:01:59 INFO - https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:01:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:01:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:01:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:01:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:02:00 INFO - Downloaded 54845828 bytes. 09:02:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:02:00 INFO - mkdir: /builds/slave/test/properties 09:02:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:00 INFO - Contents: 09:02:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:02:01 INFO - Running post-action listener: _resource_record_post_action 09:02:01 INFO - Running post-action listener: set_extra_try_arguments 09:02:01 INFO - ##### 09:02:01 INFO - ##### Running populate-webroot step. 09:02:01 INFO - ##### 09:02:01 INFO - Running pre-action listener: _resource_record_pre_action 09:02:01 INFO - Running main action method: populate_webroot 09:02:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:01 INFO - '--tppagecycles', 09:02:01 INFO - '7'], 09:02:01 INFO - 'tests': ['tsvgm']}}, 09:02:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['damp', 'tps']}, 09:02:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'tests': ['damp', 'tps']}, 09:02:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['dromaeo_dom']}, 09:02:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['a11yr', 09:02:01 INFO - 'ts_paint', 09:02:01 INFO - 'tpaint', 09:02:01 INFO - 'sessionrestore', 09:02:01 INFO - 'sessionrestore_no_auto_restore', 09:02:01 INFO - 'tabpaint']}, 09:02:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['tsvgx', 09:02:01 INFO - 'tsvgr_opacity', 09:02:01 INFO - 'tart', 09:02:01 INFO - 'tscrollx', 09:02:01 INFO - 'cart']}, 09:02:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:01 INFO - 'tsvgr_opacity', 09:02:01 INFO - 'tart', 09:02:01 INFO - 'tscrollx', 09:02:01 INFO - 'cart']}, 09:02:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'talos_options': ['--disable-e10s'], 09:02:01 INFO - 'tests': ['tp5o']}, 09:02:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'tests': ['tp5o']}, 09:02:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'talos_options': ['--disable-e10s', 09:02:01 INFO - '--xperf_path', 09:02:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:01 INFO - 'tests': ['tp5n']}, 09:02:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:01 INFO - 'talos_options': ['--xperf_path', 09:02:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:01 INFO - 'tests': ['tp5n']}}, 09:02:01 INFO - 'talos.zip': {'path': '', 09:02:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:01 INFO - Running post-action listener: _resource_record_post_action 09:02:01 INFO - ##### 09:02:01 INFO - ##### Running create-virtualenv step. 09:02:01 INFO - ##### 09:02:01 INFO - Running pre-action listener: _resource_record_pre_action 09:02:01 INFO - Running main action method: create_virtualenv 09:02:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:01 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 09:02:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:01 INFO - Using real prefix '/usr' 09:02:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:03 INFO - Installing pip.................done. 09:02:03 INFO - Return code: 0 09:02:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02: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 09:02: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 09:02:03 INFO - Using env: {'DISPLAY': ':0', 09:02:03 INFO - 'HOME': '/home/cltbld', 09:02:03 INFO - 'LANG': 'en_US.UTF-8', 09:02:03 INFO - 'LANGUAGE': 'en_US:en', 09:02:03 INFO - 'LOGNAME': 'cltbld', 09:02:03 INFO - 'MAIL': '/var/mail/cltbld', 09:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:03 INFO - 'MOZ_NO_REMOTE': '1', 09:02:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:03 INFO - 'NO_EM_RESTART': '1', 09:02:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:03 INFO - 'PWD': '/builds/slave/test', 09:02:03 INFO - 'SHELL': '/bin/bash', 09:02:03 INFO - 'SHLVL': '1', 09:02:03 INFO - 'TERM': 'linux', 09:02:03 INFO - 'TMOUT': '86400', 09:02:03 INFO - 'USER': 'cltbld', 09:02:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:03 INFO - '_': '/tools/buildbot/bin/python'} 09:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:04 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 09:02:04 INFO - Cleaning up... 09:02:04 INFO - Return code: 0 09:02:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:02:04 INFO - Using env: {'DISPLAY': ':0', 09:02:04 INFO - 'HOME': '/home/cltbld', 09:02:04 INFO - 'LANG': 'en_US.UTF-8', 09:02:04 INFO - 'LANGUAGE': 'en_US:en', 09:02:04 INFO - 'LOGNAME': 'cltbld', 09:02:04 INFO - 'MAIL': '/var/mail/cltbld', 09:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:04 INFO - 'MOZ_NO_REMOTE': '1', 09:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:04 INFO - 'NO_EM_RESTART': '1', 09:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:04 INFO - 'PWD': '/builds/slave/test', 09:02:04 INFO - 'SHELL': '/bin/bash', 09:02:04 INFO - 'SHLVL': '1', 09:02:04 INFO - 'TERM': 'linux', 09:02:04 INFO - 'TMOUT': '86400', 09:02:04 INFO - 'USER': 'cltbld', 09:02:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:04 INFO - '_': '/tools/buildbot/bin/python'} 09:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:04 INFO - Downloading/unpacking psutil>=0.7.1 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:06 INFO - Installing collected packages: psutil 09:02:06 INFO - Running setup.py install for psutil 09:02:06 INFO - building 'psutil._psutil_linux' extension 09:02:06 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 09:02: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_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:02:06 INFO - building 'psutil._psutil_posix' extension 09:02:06 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 09:02: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 09:02:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:06 INFO - Successfully installed psutil 09:02:06 INFO - Cleaning up... 09:02:06 INFO - Return code: 0 09:02:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02: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 09:02: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 09:02:06 INFO - Using env: {'DISPLAY': ':0', 09:02:06 INFO - 'HOME': '/home/cltbld', 09:02:06 INFO - 'LANG': 'en_US.UTF-8', 09:02:06 INFO - 'LANGUAGE': 'en_US:en', 09:02:06 INFO - 'LOGNAME': 'cltbld', 09:02:06 INFO - 'MAIL': '/var/mail/cltbld', 09:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:06 INFO - 'MOZ_NO_REMOTE': '1', 09:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:06 INFO - 'NO_EM_RESTART': '1', 09:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:06 INFO - 'PWD': '/builds/slave/test', 09:02:06 INFO - 'SHELL': '/bin/bash', 09:02:06 INFO - 'SHLVL': '1', 09:02:06 INFO - 'TERM': 'linux', 09:02:06 INFO - 'TMOUT': '86400', 09:02:06 INFO - 'USER': 'cltbld', 09:02:06 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:06 INFO - '_': '/tools/buildbot/bin/python'} 09:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:02:07 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 09:02:07 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 09:02:07 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 09:02:07 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 09:02:07 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 09:02:07 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 09:02:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:02:09 INFO - Installing collected packages: mozsystemmonitor 09:02:09 INFO - Running setup.py install for mozsystemmonitor 09:02:09 INFO - Successfully installed mozsystemmonitor 09:02:09 INFO - Cleaning up... 09:02:09 INFO - Return code: 0 09:02:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:09 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')]} 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:09 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')]} 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:09 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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02:09 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 09:02:09 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 09:02:09 INFO - Using env: {'DISPLAY': ':0', 09:02:09 INFO - 'HOME': '/home/cltbld', 09:02:09 INFO - 'LANG': 'en_US.UTF-8', 09:02:09 INFO - 'LANGUAGE': 'en_US:en', 09:02:09 INFO - 'LOGNAME': 'cltbld', 09:02:09 INFO - 'MAIL': '/var/mail/cltbld', 09:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:09 INFO - 'MOZ_NO_REMOTE': '1', 09:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:09 INFO - 'NO_EM_RESTART': '1', 09:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:09 INFO - 'PWD': '/builds/slave/test', 09:02:09 INFO - 'SHELL': '/bin/bash', 09:02:09 INFO - 'SHLVL': '1', 09:02:09 INFO - 'TERM': 'linux', 09:02:09 INFO - 'TMOUT': '86400', 09:02:09 INFO - 'USER': 'cltbld', 09:02:09 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:09 INFO - '_': '/tools/buildbot/bin/python'} 09:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:12 INFO - Downloading docopt-0.6.1.tar.gz 09:02:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:12 INFO - Running setup.py install for blobuploader 09:02:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:12 INFO - Running setup.py install for requests 09:02:12 INFO - Running setup.py install for docopt 09:02:12 INFO - Successfully installed blobuploader requests docopt 09:02:12 INFO - Cleaning up... 09:02:12 INFO - Return code: 0 09:02:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:12 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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02:12 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 09:02:12 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 09:02:12 INFO - Using env: {'DISPLAY': ':0', 09:02:12 INFO - 'HOME': '/home/cltbld', 09:02:12 INFO - 'LANG': 'en_US.UTF-8', 09:02:12 INFO - 'LANGUAGE': 'en_US:en', 09:02:12 INFO - 'LOGNAME': 'cltbld', 09:02:12 INFO - 'MAIL': '/var/mail/cltbld', 09:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:12 INFO - 'MOZ_NO_REMOTE': '1', 09:02:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:12 INFO - 'NO_EM_RESTART': '1', 09:02:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:12 INFO - 'PWD': '/builds/slave/test', 09:02:12 INFO - 'SHELL': '/bin/bash', 09:02:12 INFO - 'SHLVL': '1', 09:02:12 INFO - 'TERM': 'linux', 09:02:12 INFO - 'TMOUT': '86400', 09:02:12 INFO - 'USER': 'cltbld', 09:02:12 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:12 INFO - '_': '/tools/buildbot/bin/python'} 09:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:12 INFO - Running setup.py (path:/tmp/pip-0BI1o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:13 INFO - Running setup.py (path:/tmp/pip-H3nUe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:13 INFO - Running setup.py (path:/tmp/pip-0ybJt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:13 INFO - Running setup.py (path:/tmp/pip-zbLyNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:13 INFO - Running setup.py (path:/tmp/pip-_tDpMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:13 INFO - Running setup.py (path:/tmp/pip-YPh_co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:13 INFO - Running setup.py (path:/tmp/pip-OyGAYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:13 INFO - Running setup.py (path:/tmp/pip-6kkZl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:13 INFO - Running setup.py (path:/tmp/pip-LYfZml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:13 INFO - Running setup.py (path:/tmp/pip-dc2Y7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:13 INFO - Running setup.py (path:/tmp/pip-3W4rxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:13 INFO - Running setup.py (path:/tmp/pip-4d5p5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:13 INFO - Running setup.py (path:/tmp/pip-VI8HXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:13 INFO - Running setup.py (path:/tmp/pip-AeZM5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:13 INFO - Running setup.py (path:/tmp/pip-xjUXSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:14 INFO - Running setup.py (path:/tmp/pip-iXfQLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:14 INFO - Running setup.py (path:/tmp/pip-xFLaMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:14 INFO - Running setup.py install for manifestparser 09:02:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozcrash 09:02:14 INFO - Running setup.py install for mozdebug 09:02:14 INFO - Running setup.py install for mozdevice 09:02:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozfile 09:02:14 INFO - Running setup.py install for mozhttpd 09:02:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozinfo 09:02:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozInstall 09:02:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozleak 09:02:15 INFO - Running setup.py install for mozlog 09:02:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for moznetwork 09:02:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozprocess 09:02:15 INFO - Running setup.py install for mozprofile 09:02:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozrunner 09:02:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozscreenshot 09:02:15 INFO - Running setup.py install for moztest 09:02:15 INFO - Running setup.py install for mozversion 09:02:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:15 INFO - Cleaning up... 09:02:15 INFO - Return code: 0 09:02:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02: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 09:02: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 09:02:15 INFO - Using env: {'DISPLAY': ':0', 09:02:15 INFO - 'HOME': '/home/cltbld', 09:02:15 INFO - 'LANG': 'en_US.UTF-8', 09:02:15 INFO - 'LANGUAGE': 'en_US:en', 09:02:15 INFO - 'LOGNAME': 'cltbld', 09:02:15 INFO - 'MAIL': '/var/mail/cltbld', 09:02:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:15 INFO - 'MOZ_NO_REMOTE': '1', 09:02:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:15 INFO - 'NO_EM_RESTART': '1', 09:02:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:15 INFO - 'PWD': '/builds/slave/test', 09:02:15 INFO - 'SHELL': '/bin/bash', 09:02:15 INFO - 'SHLVL': '1', 09:02:15 INFO - 'TERM': 'linux', 09:02:15 INFO - 'TMOUT': '86400', 09:02:15 INFO - 'USER': 'cltbld', 09:02:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:15 INFO - '_': '/tools/buildbot/bin/python'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:16 INFO - Running setup.py (path:/tmp/pip-SiJM43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:16 INFO - Running setup.py (path:/tmp/pip-OFtqWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:16 INFO - Running setup.py (path:/tmp/pip-HfEJ4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:16 INFO - Running setup.py (path:/tmp/pip-2OWhPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:16 INFO - Running setup.py (path:/tmp/pip-eQo8s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:16 INFO - Running setup.py (path:/tmp/pip-fBzL8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:16 INFO - Running setup.py (path:/tmp/pip-QiOEUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:16 INFO - Running setup.py (path:/tmp/pip-i_j6L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:16 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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:16 INFO - Running setup.py (path:/tmp/pip-lWcl2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02: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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:16 INFO - Running setup.py (path:/tmp/pip-eGQgU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02: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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:16 INFO - Running setup.py (path:/tmp/pip-jc7jWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02: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)) 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:17 INFO - Running setup.py (path:/tmp/pip-L5qkgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:17 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)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:17 INFO - Running setup.py (path:/tmp/pip-J0kkvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:17 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)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:17 INFO - Running setup.py (path:/tmp/pip-riTNWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:17 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)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:17 INFO - Running setup.py (path:/tmp/pip-ZYjUbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:17 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)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:17 INFO - Running setup.py (path:/tmp/pip-qv58ZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:17 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)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:17 INFO - Running setup.py (path:/tmp/pip-7p7Mb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:17 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)) 09:02:17 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)) 09:02:17 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)) 09:02:17 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)) 09:02:17 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)) 09:02:17 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)) 09:02:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:17 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 09:02:17 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 09:02:17 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 09:02:17 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 09:02:17 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 09:02:17 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 09:02:19 INFO - Downloading blessings-1.6.tar.gz 09:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:19 INFO - Installing collected packages: blessings 09:02:19 INFO - Running setup.py install for blessings 09:02:19 INFO - Successfully installed blessings 09:02:19 INFO - Cleaning up... 09:02:19 INFO - Return code: 0 09:02:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:19 INFO - Reading from file tmpfile_stdout 09:02:19 INFO - Current package versions: 09:02:19 INFO - argparse == 1.2.1 09:02:19 INFO - blessings == 1.6 09:02:19 INFO - blobuploader == 1.2.4 09:02:19 INFO - docopt == 0.6.1 09:02:19 INFO - manifestparser == 1.1 09:02:19 INFO - mozInstall == 1.12 09:02:19 INFO - mozcrash == 0.17 09:02:19 INFO - mozdebug == 0.1 09:02:19 INFO - mozdevice == 0.48 09:02:19 INFO - mozfile == 1.2 09:02:19 INFO - mozhttpd == 0.7 09:02:19 INFO - mozinfo == 0.9 09:02:19 INFO - mozleak == 0.1 09:02:19 INFO - mozlog == 3.1 09:02:19 INFO - moznetwork == 0.27 09:02:19 INFO - mozprocess == 0.22 09:02:19 INFO - mozprofile == 0.28 09:02:19 INFO - mozrunner == 6.11 09:02:19 INFO - mozscreenshot == 0.1 09:02:19 INFO - mozsystemmonitor == 0.0 09:02:19 INFO - moztest == 0.7 09:02:19 INFO - mozversion == 1.4 09:02:19 INFO - psutil == 3.1.1 09:02:19 INFO - requests == 1.2.3 09:02:19 INFO - wsgiref == 0.1.2 09:02:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:19 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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02:19 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 09:02:19 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 09:02:19 INFO - Using env: {'DISPLAY': ':0', 09:02:19 INFO - 'HOME': '/home/cltbld', 09:02:19 INFO - 'LANG': 'en_US.UTF-8', 09:02:19 INFO - 'LANGUAGE': 'en_US:en', 09:02:19 INFO - 'LOGNAME': 'cltbld', 09:02:19 INFO - 'MAIL': '/var/mail/cltbld', 09:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:19 INFO - 'MOZ_NO_REMOTE': '1', 09:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:19 INFO - 'NO_EM_RESTART': '1', 09:02:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:19 INFO - 'PWD': '/builds/slave/test', 09:02:19 INFO - 'SHELL': '/bin/bash', 09:02:19 INFO - 'SHLVL': '1', 09:02:19 INFO - 'TERM': 'linux', 09:02:19 INFO - 'TMOUT': '86400', 09:02:19 INFO - 'USER': 'cltbld', 09:02:19 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:19 INFO - '_': '/tools/buildbot/bin/python'} 09:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02: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)) 09:02:19 INFO - Cleaning up... 09:02:19 INFO - Return code: 0 09:02:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02: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 09:02: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 09:02:19 INFO - Using env: {'DISPLAY': ':0', 09:02:19 INFO - 'HOME': '/home/cltbld', 09:02:19 INFO - 'LANG': 'en_US.UTF-8', 09:02:19 INFO - 'LANGUAGE': 'en_US:en', 09:02:19 INFO - 'LOGNAME': 'cltbld', 09:02:19 INFO - 'MAIL': '/var/mail/cltbld', 09:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:19 INFO - 'MOZ_NO_REMOTE': '1', 09:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:19 INFO - 'NO_EM_RESTART': '1', 09:02:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:19 INFO - 'PWD': '/builds/slave/test', 09:02:19 INFO - 'SHELL': '/bin/bash', 09:02:19 INFO - 'SHLVL': '1', 09:02:19 INFO - 'TERM': 'linux', 09:02:19 INFO - 'TMOUT': '86400', 09:02:19 INFO - 'USER': 'cltbld', 09:02:19 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:19 INFO - '_': '/tools/buildbot/bin/python'} 09:02:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:20 INFO - Downloading/unpacking jsonschema 09:02:20 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 09:02:20 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 09:02:20 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 09:02:20 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 09:02:20 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 09:02:20 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 09:02:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:22 INFO - Downloading/unpacking functools32 (from jsonschema) 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:22 INFO - warning: no files found matching '*.txt' 09:02:22 INFO - no previously-included directories found matching 'build' 09:02:22 INFO - no previously-included directories found matching 'dist' 09:02:22 INFO - no previously-included directories found matching '.git*' 09:02:22 INFO - Installing collected packages: jsonschema, functools32 09:02:22 INFO - Running setup.py install for functools32 09:02:22 INFO - warning: no files found matching '*.txt' 09:02:22 INFO - no previously-included directories found matching 'build' 09:02:22 INFO - no previously-included directories found matching 'dist' 09:02:22 INFO - no previously-included directories found matching '.git*' 09:02:22 INFO - Successfully installed jsonschema functools32 09:02:22 INFO - Cleaning up... 09:02:22 INFO - Return code: 0 09:02:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:02:22 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')]} 09:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 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')]} 09:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 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 0x7fec730d7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14ae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157a7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, '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': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', '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 09:02:22 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 09:02:22 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 09:02:22 INFO - Using env: {'DISPLAY': ':0', 09:02:22 INFO - 'HOME': '/home/cltbld', 09:02:22 INFO - 'LANG': 'en_US.UTF-8', 09:02:22 INFO - 'LANGUAGE': 'en_US:en', 09:02:22 INFO - 'LOGNAME': 'cltbld', 09:02:22 INFO - 'MAIL': '/var/mail/cltbld', 09:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:22 INFO - 'MOZ_NO_REMOTE': '1', 09:02:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:22 INFO - 'NO_EM_RESTART': '1', 09:02:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:22 INFO - 'PWD': '/builds/slave/test', 09:02:22 INFO - 'SHELL': '/bin/bash', 09:02:22 INFO - 'SHLVL': '1', 09:02:22 INFO - 'TERM': 'linux', 09:02:22 INFO - 'TMOUT': '86400', 09:02:22 INFO - 'USER': 'cltbld', 09:02:22 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:22 INFO - '_': '/tools/buildbot/bin/python'} 09:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:22 INFO - Downloading/unpacking flake8 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:22 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 09:02:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:02:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:02:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02: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 09:02:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:02:25 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 09:02:25 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 09:02:25 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 09:02:25 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 09:02:25 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 09:02:25 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 09:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:02:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:25 INFO - no previously-included directories found matching 'docs/_build' 09:02:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:02:25 INFO - Running setup.py install for pep8 09:02:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:25 INFO - no previously-included directories found matching 'docs/_build' 09:02:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:02:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:02:25 INFO - Cleaning up... 09:02:25 INFO - Return code: 0 09:02:25 INFO - Running post-action listener: _resource_record_post_action 09:02:25 INFO - Running post-action listener: _start_resource_monitoring 09:02:25 INFO - Starting resource monitoring. 09:02:25 INFO - ##### 09:02:25 INFO - ##### Running install step. 09:02:25 INFO - ##### 09:02:25 INFO - Running pre-action listener: _resource_record_pre_action 09:02:25 INFO - Running main action method: install 09:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:25 INFO - Reading from file tmpfile_stdout 09:02:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:25 INFO - Reading from file tmpfile_stdout 09:02:25 INFO - Output received: 09:02:25 INFO - Usage: mozinstall [options] installer 09:02:25 INFO - Options: 09:02:25 INFO - -h, --help show this help message and exit 09:02:25 INFO - -d DEST, --destination=DEST 09:02:25 INFO - Directory to install application into. [default: 09:02:25 INFO - "/builds/slave/test"] 09:02:25 INFO - --app=APP Application being installed. [default: firefox] 09:02:25 INFO - mkdir: /builds/slave/test/build/application 09:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:02:41 INFO - Reading from file tmpfile_stdout 09:02:41 INFO - Output received: 09:02:41 INFO - /builds/slave/test/build/application/firefox/firefox 09:02:41 INFO - Running post-action listener: _resource_record_post_action 09:02:41 INFO - ##### 09:02:41 INFO - ##### Running run-tests step. 09:02:41 INFO - ##### 09:02:41 INFO - Running pre-action listener: _resource_record_pre_action 09:02:41 INFO - Running main action method: run_tests 09:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:41 INFO - Python 2.7.3 09:02:41 INFO - Return code: 0 09:02:41 INFO - grabbing minidump binary from tooltool 09:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:41 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 0x1609640>, '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 0x160a540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160a9d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:41 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 09:02:41 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 09:02:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:41 INFO - Return code: 0 09:02:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:02:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:02:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:02:42 INFO - Return code: 0 09:02:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 09:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 09:02:42 INFO - Using env: {'DISPLAY': ':0', 09:02:42 INFO - 'HOME': '/home/cltbld', 09:02:42 INFO - 'LANG': 'en_US.UTF-8', 09:02:42 INFO - 'LANGUAGE': 'en_US:en', 09:02:42 INFO - 'LOGNAME': 'cltbld', 09:02:42 INFO - 'MAIL': '/var/mail/cltbld', 09:02:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:42 INFO - 'MOZ_NO_REMOTE': '1', 09:02:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:42 INFO - 'NO_EM_RESTART': '1', 09:02:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:42 INFO - 'PWD': '/builds/slave/test', 09:02:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:42 INFO - 'SHELL': '/bin/bash', 09:02:42 INFO - 'SHLVL': '1', 09:02:42 INFO - 'TERM': 'linux', 09:02:42 INFO - 'TMOUT': '86400', 09:02:42 INFO - 'USER': 'cltbld', 09:02:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890', 09:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:42 INFO - '_': '/tools/buildbot/bin/python'} 09:02:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 09:02:42 INFO - mozversion application_buildid: 20160428073807 09:02:42 INFO - mozversion application_changeset: 4292da9df16b220f30921100282264a34cd2ff03 09:02:42 INFO - mozversion application_display_name: Nightly 09:02:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:42 INFO - mozversion application_name: Firefox 09:02:42 INFO - mozversion application_remotingname: firefox 09:02:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:42 INFO - mozversion application_vendor: Mozilla 09:02:42 INFO - mozversion application_version: 49.0a1 09:02:42 INFO - mozversion platform_buildid: 20160428073807 09:02:42 INFO - mozversion platform_changeset: 4292da9df16b220f30921100282264a34cd2ff03 09:02:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:42 INFO - mozversion platform_version: 49.0a1 09:02:42 INFO - using testdate: 1461859362 09:02:42 INFO - actual date: 1461859362 09:02:42 INFO - starting webserver on 'localhost:48810' 09:02:42 INFO - SUITE-START | Running 2 tests 09:02:42 INFO - TEST-START | dromaeo_css 09:02:42 INFO - operating with platform_type : linux_ 09:02:42 INFO - Initialising browser for dromaeo_css test... 09:02:42 INFO - TEST-INFO | started process 7481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUOeCRw/profile http://localhost:48810/getInfo.html) 09:02:46 INFO - PROCESS | 7481 | 09:02:46 INFO - PROCESS | 7481 | (process:7546): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:02:47 INFO - PROCESS | 7481 | __metrics Screen width/height:1600/1200 09:02:47 INFO - PROCESS | 7481 | colorDepth:24 09:02:47 INFO - PROCESS | 7481 | Browser inner width/height: 1024/697 09:02:47 INFO - PROCESS | 7481 | __metrics 09:02:48 ERROR - PROCESS | 7481 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:02:49 INFO - TEST-INFO | 7481: exit 0 09:02:49 INFO - Browser initialized. 09:02:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:49 INFO - TEST-INFO | started process 7597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUOeCRw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:51 INFO - PROCESS | 7597 | 09:02:51 INFO - PROCESS | 7597 | (process:7655): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:03:01 INFO - PROCESS | 7597 | RSS: Main: 166150144 09:03:01 INFO - PROCESS | 7597 | 09:03:50 INFO - PROCESS | 7597 | 1461859430898 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] 09:06:03 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48810/tests/dromaeo/cssquery-ext.html) 09:06:05 INFO - PROCESS | 7597 | RSS: Main: 168370176 09:06:05 INFO - PROCESS | 7597 | 09:09:07 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-ext.html (next: http://localhost:48810/tests/dromaeo/cssquery-jquery.html) 09:09:08 INFO - PROCESS | 7597 | RSS: Main: 166842368 09:09:08 INFO - PROCESS | 7597 | 09:12:09 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48810/tests/dromaeo/cssquery-mootools.html) 09:12:10 INFO - PROCESS | 7597 | RSS: Main: 167854080 09:12:10 INFO - PROCESS | 7597 | 09:15:12 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48810/tests/dromaeo/cssquery-prototype.html) 09:15:12 INFO - PROCESS | 7597 | RSS: Main: 166154240 09:15:12 INFO - PROCESS | 7597 | 09:18:15 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48810/tests/dromaeo/cssquery-yui.html) 09:18:16 INFO - PROCESS | 7597 | RSS: Main: 167313408 09:18:16 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | Cycle 1(1): loaded http://localhost:48810/tests/dromaeo/cssquery-yui.html (next: http://localhost:48810/tests/dromaeo/cssquery-dojo.html) 09:21:18 INFO - PROCESS | 7597 | RSS: Main: 169250816 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | __start_tp_report 09:21:18 INFO - PROCESS | 7597 | _x_x_mozilla_page_load 09:21:18 INFO - PROCESS | 7597 | _x_x_mozilla_page_load_details 09:21:18 INFO - PROCESS | 7597 | |i|pagename|runs| 09:21:18 INFO - PROCESS | 7597 | |0;dojo.html;2580.238829545284;2668.5863691127875;2674.1309074550763;2694.016683910373;2697.069511018699;4702.318163866242;4724.267738500534;4755.809767609296;4786.4495583007965;4808.687435316702;4597.287420449836;4648.953510464894;4652.418447694038;4665.76671166442;4670.346151538779;4285.764282964444;4299.720518166325;4399.472063352402;4484.865454036368;4496.325551167319;4380.408644832954;4420.491643461011;4424.137293227822;4426.955730442703;4427.048184640302;4361.4112094867105;4398.186335527937;4440.866773996785;4444.400005999181;4468.553144685522;4290.120525292339;4365.869023929287;4400.559944005606;4427.335899615051;4428.800703968329;4316.654667626613;4327.697061205717;4383.32058530925;4405.889852753675;4442.888927776799;4560.429946256718;4632.420947381577;4663.60359369454;4670.509596492388;4679.274712419547;5012.147934851084;5025.221090730943;5035.496450354972;5042.243663450475;5088.592912566985;5032.396112466491;5053.570446512051;5070.08738427083;5074.695518268915;5089.211172268304;4527.660425468103;4694.6713730039255;4762.809487620491;4764.76176191196;4769.833055843029;5113.488651134857;5128.12821820284;5132.897342053138;5143.4085080216255;5151.5106064923775;4976.129177393942;4977.228529577921;4981.227909674007;4986.301917731521;4994.850154495371;5028.446870844205;5059.418166910852;5062.164742817467;5112.130937740593;5145.639805213674;4951.282064100757;4972.602191824645;4986.501349864984;5005.924911126336;5048.3184770056305;4835.443923948791;4960.975195124001;4984.925226121611;4997.525235102659;5045.672031318026;4061.5126184858004;4179.540250572434;4181.435506206684;4223.155368926227;4244.617984381359;3630.945535816965;3692.5015122958594;3695.7043436525014;3707.629237076325;3710.350688629533;4318.093200427883;4412.985013446925;4437.755923424243;4438.689291749611;4495.19086564415;4997.275395067695;5056.216286475602;5068.341115654941;5074.517920797518;5097.1844504879045;4408.448943882015;4461.486929003207;4466.59800617946;4477.104579084196;4514.345419914094;4363.869083927487;4444.599986001211;4467.7319360838455;4499.707519011254;4525.094981003813;4719.9764001180465;4750.239961606127;4756;4757.809687612456;4773.331733557374;4958.504149585013;5011.699298042129;5015.84952451427;5032.974835125801;5066.670666406671;4670.136024835417;4731.787069581808;4737.4788773235605;4807.663463557517;4815.590674792682;4996.525330093636;5004.524570165829;5018.071656743514;5044.54599086084;5044.722540260321;5081.618878584047;5085.364329458817;5090.185570308733;5111.616628752933;5112.514311140436;4748.88127796794;4850.80596776125;4896.82861099874;4922.187839006524;4936.555709986119;4832.468428472795;4846.927296090491;4869.050535145612;4882.82910098159;4929.679570827885;4690.92963605553;4804.399450068741;4806.182948898626;4807.567318941312;4873.902521949471;4830.903381932271;4837.95162048375;4941.962187940573;4979.153543897619;5019.372578427697;4844.612431005582;4871.439784424837;4888.828890988798;4891.26631005352;4932.8766780830765 09:21:18 INFO - PROCESS | 7597 | |1;ext.html;567.6707509644405;606.2876120558344;610.8350745298769;626.4080443980438;636.5385095805539;34346.48480272795;34839.12902177447;35304;35388.2922341553;35400;36072;36570;36736.448953265724;36761.242906782034;36951;7554.169041405455;7759.340456061242;7804.570748608824;7813.9609301953415;7835.608219589027;7755.534667919943;7839.294463498284;7870.921290787105;7875.8424831503335;7929.048514178277;7804.29761321481;7819.279758453131;7858.607069646523;7914;7929.643166057541;7803.570803605828;7851.939988101619;7900.130985591581;7904.051513818321;7908.406869484783;7760.611969401564;7843.960780196063;7874.881876771853;7897.328727058207;7897.723579674741;25902.22293331203;36451.63548364509;36808.81595592005;37028.25943481142;37262;2845.758110560605;2942.382099759032;2985.149232468758;3370.8988730337883;3595.8202089895663;27449.31376715578;27675.169744907686;27953.720462795314;27978.580321295198;28394.148175554765;3101.922451938697;3136.952945705816;3151.322465669892;3159.3207460396125;3202.455582550972;216322;216976;217445;218187.9999999984;218398;30964.22589435237;32483.67516324854;33678.32643347119;33704;33770.32459350799;32107.307659618975;32342.514862277083;32421;32443.35113297768;32516.187095322806;12613.99088072952;12663.936680316723;12764.872351276368;12796.168249063781;12874.613761587167;10654.467276636136;10712.46437678113;10729.141668666643;10743.14054875608;10745.301555398873;8186.918130818615;8260.173982601811;8287.41988060842;8288.461250018729;8314.127016663284;3196.168996061041;3205.5512228288057;3215.9839200804313;3268.6404495505476;3276.6559511251453;947.3557980573139;958.4297343080856;976.7753416714196;1002.5137808163036;1018.6689325968941;28451.430971380458;28741.856290718413;28808.42383152325;28852.711472885003;28940;985.6796541124163;985.9795112058985;991.038692468319;1018.8471729240525;1023.1405619279841;15313.540593782205;15431.92369124225;15443.459478918185;15544.222788860512;15652.295646695926;385936;392432;392539;395610;397955;50080.24879626808;50803.74598126986;51174.488255117714;51271.230931536054;51275.23087153696;1154.1747650429984;1244.328062846053;1269.238456925837;1282.5767496726057;1283.9679008024875;26962.056328028426;27812.44375112527;27966.440671186054;28139.859300703367;28286.43427131487;40075.39886901757;41534.376984345865;41672.58327416687;41844.37233441562;42039;258463;262028;262593;263232;264029;38373.23253534858;38714.22571548609;38800.80599596984;38888.611113888495;38984;43171.13657726765;43301.783491082344;43965;44597.77701111474;44823.77588112169;36423.27153456863;37261.25477490489;37301.81349093237;37370.25259494849;37617.62382376141;36033.81983090068;36113.27773444569;36489.452658209615;36582.63417365899;36845.26309473849;32419.837900810344;34189.487157693155;36619.81690091533;36854;36918.26163476769;31282.53076203813;33281.83359083189;33454.4263466638;33770;33990.830045849616;25046;25670.358241044123;25673.973041078894;25832.225033249033;26030 09:21:18 INFO - PROCESS | 7597 | |2;jquery.html;373.5386797305328;373.65063665472786;375.54746530430833;377.3622577843446;388.4833171881398;16101.114438705867;16255;17347.21937512812;17351.305947762074;17380.652386952268;16128.354865805382;16429.671406571862;17261.91369043153;17277.30890764368;17315.653686926253;5071.644984851053;5215.660982036175;5248.6063545234065;5259.211118332242;5261.342332208474;3737.3085979093817;5132.076226279269;5643.661380317194;5993.820185394424;6015.488683461889;6081.54388420868;6082.087686846964;6083.756649734005;6095.512359011268;6115.235595550556;4937.5556199942;5231.790728370861;5235.842924712227;5244.55421289189;5246.055709972204;5022.522860328263;5082.517160869711;5219.164933610642;5408.323959505062;5409.6213265071465;11960.760784784256;12159.270443773403;12191.268523888595;12286.692832679255;17837.286508539644;1844.5019844641872;1849.3989453427555;1878.4834170603058;1951.4633475794128;2090.0594732370496;8975;9467.479288639122;9948.95025524875;10723.088537474325;13720.45118195271;1841.383136649224;1888.2163901980662;1904.5714714189335;1951.443838506019;2021.777604463508;66565.3343466564;67099;69988.6500567494;74643.62678186629;75100.24899750987;9605.423674579548;9782.706518804447;9883.258755593359;9900.900990990142;9934.652287170058;13591.728165436636;13650.044496885122;13661.248631325372;13665.111767735065;13724.725505489834;7045.225025247219;7130.786076417612;7191.849304111422;7341.825307950702;7404.96297518509;6191.19514463117;6376.84057898547;6468.320826313264;6508.414242718178;6509.479241660656;4111.403846442249;4233.618974292329;4265.87202383929;4295.914081718348;4464.352668862996;1764.3560100563202;1790.3375750972223;1815.7639506864023;1832.0931139085972;1839.5768973135987;848.9787755306044;901.5041727050096;987.3532835992293;998.5855869814092;1032.3547782635853;15833.050016999017;15863.920680396524;16695.582610434594;16727.414540491267;17112.828871711125;858.9098144694717;881.2861582118504;885.583775625455;886.4814083760944;887.9511626860456;9186.713860059597;9510.334276600572;9611.279154063473;9745.658901938397;9777.608895644236;137864;138486;138711;139371;401744;72628;76339;76750;77252.2274777245;80721;944.641036406175;987.629638885418;997.9970130019284;999.1557134221571;1007.8740157480315;14958;15014.174220417983;15019.624509387353;15186.544403667907;15672.843271567139;24785.380365491008;31738.36523269568;32248.83875580607;33215;33863.83068084644;45072.77463612661;46903.53096469128;48414.27378589253;48695;49389;7434.256574342522;7600.315971562586;7639.656215470428;7779.222077792176;7867.763967080997;10717.624883129052;11276.154288428565;11341.603043893425;11376.658700239006;11421.828672569753;9784.608615655294;9999.55002024896;10040.598376064876;10077.344972576759;10102.292839501164;9805.950970245103;10009.54957026921;10045.748856278358;10150.390976541432;10194.796104078025;9662.13040826329;9718.465484398426;10080.445575493419;10163.44101074448;10195.643152489627;7112.893306600508;7411.184769675439;7509.88735168962;7521.247875212435;7746.147923728273;9586.185174260267;9713.611455541983;9721.076497732704;9772.462514561767;9800.558974846292 09:21:18 INFO - PROCESS | 7597 | |3;mootools.html;1443.9494617688376;1473.6242258224154;1599.7760313556105;1747.606788472593;1773.405909020478;6792.18493780747;7432.219616940224;7511.474196806219;7864;7865.960670196649;7614.4669873108905;7668.501547399415;7680.078390593123;7699.846003079935;7709.845803083935;2186.6610675345346;2210.0938615167784;2210.745764237109;2228.1533017453344;2241.12596087526;2169.370882444089;2201.5486825200874;2218.7670294619074;2222.5999320122373;2227.7438094619083;2184.20276599041;2185.0276626901036;2191.5726433345503;2219.3785739992827;2247.045005872506;2128.12746773822;2144.5603651251454;2149.7635260121374;2151.741790985084;2153.1710291537793;2166.739991201066;2178.7821217878245;2184.7378314602192;2189.0477642225524;2197.1760589778833;7700.422468314843;7706.306432421109;7802.921970780277;7817.648205830751;7859.96070019652;4405.669574781872;4521.276595744665;4525.909481810379;4539.001419687664;4540.682152249344;2699.797515186369;2799.398129402168;2802.1313392848283;2830.221689035511;2855.029290041375;878.4816957994814;878.872563478292;891.5542228885557;914.1133100892124;916.2761418479394;10040.347377420445;10106.090451859369;10108.342957707726;10109.393436393766;10116.898831011742;4210.263203939298;4236.428082208862;4271.466066741657;4276.013491696852;4280.743155410685;8698.652053917898;8707.216350528483;8736.213740763362;8756.255718263892;8776.429532080394;4434.11317736454;4440.71135376206;4494.977525112354;4497.572730590588;4522.819087236539;3604.747667663291;4030.8992275193355;4079.796010199478;4097.282975479279;4225.154969006211;3416.5046068319953;3473.5237524052177;3533.304014073259;3559.3059353425833;3571.5535558055244;1200.7478429529897;1215.6353094071744;1224.4204975581579;1224.8836360545913;1236.8886800187847;734.5445823589408;878.9516576588348;885.8051228729669;891.7636826241052;895.6686026170358;5202.401723801811;5333.893322133613;5338.199270109515;5384.838454846361;5400.702961337086;406.82913176465956;407.80425395810175;423.6970566045292;424.20249930131155;426.04565772669616;3469.5663042119736;3636.5272514572935;3651.1419816343137;3666.4866918631415;3685.09715119792;9333.113354231336;9694.36986595869;9719.56261968213;9772.95113524442;9792.853107203398;8942.50816205102;9718.173955213886;9755.756106097262;9760.658376956773;9777.9022209777;7074.434045276365;7191.89212161818;7198.388137008308;7206.171290301578;7226.096737907762;5134;5292;5327.9200811987075;5387.515123638891;5427.755750991136;8552.315814734682;8640.395172338127;8723.12768723148;8767.868481972902;8781.692640757543;8604.82790344177;8802.823943521222;8861.556922153995;8873.245774109015;8923.509206993678;6063.423974722395;6631.137952066393;6670.833229169116;6747.359000894907;6894.310568943066;7544.886826697494;7615.4669173157345;7626.237376262329;7642.579658118857;7660.463767536442;6621.635810030494;6724.092247546519;6810.523263371517;6835.897461537981;6935.687894044881;6692.732290708317;6702.430293424918;6754.797356079325;6756.628385438848;6775.491838112062;6441.355864413521;6467.353264673495;6505.577137486048;6521.804345869631;6523.739050437929;4395.1429471252795;4605.953940460552;4623.375844261116;4677.46209185948;4721.079389519148;4512.9323060153465;4656.301554766812;4690.859274221779;4704.858854234379;4773.260144677581 09:21:18 INFO - PROCESS | 7597 | |4;prototype.html;301.42728815251036;309.6315384692216;318.89954664280737;320.18193515567714;320.6617019044908;4790.97604511977;4863.951360486403;4886.657933944621;4963.2555116732465;4992.126377883865;4902.65681402302;4925.82759603414;4935.136351138554;4950.282209079682;4952.059108769331;3934.9803250983705;3944.5069366329208;3945.2109578084355;3953.1105501262273;3965.325894597911;3647.6717095461404;3937.322620617979;3947.7631342119566;3953.268645300614;3955.1891862168186;3766.5291838520184;3795.5065841440573;3796.411556208778;3798.924021519582;3825.67481764049;3776.339140650389;3802.543694756647;3814.999999999986;3821.1211421372964;3852.768833869977;3333.683300086488;3335.31626016666;3347.581552305962;3350;3357.429237029686;4598.287265473857;4691.29630555416;4724.338592597005;4760.428748550196;4764.928526072112;4294.978525107385;4300.65594752419;4337.414449049369;4340.891477713051;4343.544912454332;5121.359830021247;5133.358330208724;5144.279800827887;5156.767945442464;5178.404483484373;3476.721862251014;3624;3627.5102861113673;3635.8909232723063;3646.927061458756;5331.706756128411;5379.919301210446;5413.431589683105;5421.728913554307;5432;3757.9436308455397;3799.544054713452;3801.2777572261734;3801.334766415866;3804.6766024887643;3800.3539398302355;3822.8853134406013;3826.2921359548573;3827.483289755847;3852.6725228355904;4231.724937879028;4322.8703138905885;4346.544497124793;4368.301071828492;4375.693701440869;5123.7438128093445;5137.8715532112;5146.742662866842;5148.4851514848215;5176.352955880515;1311.6196303072193;1347.1512946843623;1387.5143699705225;1392.276016471429;1394.6652803327129;918.3387960668308;928.9038934057742;943.8820147481565;943.9716808495756;947.3747326764302;4837.854864354075;4842.927356089662;4858.514148585184;4867.342908707279;4873.879007828148;5602.579806514446;5671.858203544862;5680.829575112753;5694.715264236772;5707.6575405475805;4725.929111063337;4873.1959226727195;4876.902461950741;4895.681780684244;4904.239842824368;3010.864511097006;3018.139830148441;3033.924151896176;3055.1292881529102;3063.7855350125724;5278.9472105278455;5280.287161233261;5321.600879934021;5329.786808527616;5343.358796944314;5499.615026948155;5739.311282646109;5757.65454072757;5786.971065144648;5809.302883653989;5302.416734159239;5305.283786688825;5326.707031113248;5332.146856503018;5338.3593968724;5497.972510137423;5654.15187721845;5664.716764161858;5676.545876329967;5685.715714214355;5539.307586551681;5547.611667183259;5549.278593782782;5551.611387203018;5568.248286481436;5292.100342941632;5299.205119232146;5314.946850531445;5318.547923426552;5336.306280183551;3508.771929824655;3928.2339943710704;3941.664958478447;3944.467496887998;3950.841966321315;5149.897002059863;5336.973315133399;5354.464553544614;5364.329458817648;5397.379301380234;5073.518015788494;5202.895942081062;5209.166533354645;5211.55701765339;5229.3133340993745;5161.612879034163;5202.843914682566;5222.321098257202;5227.973860130827;5238.266642670105;4888.413390393175;5124.5131712487255;5133.537981581676;5168.9483105169975;5180.896382072413;5274.736263186902;5281.55106815925;5294.391145018372;5303.443138470405;5322.574194064543;5140.614453915897;5168.457311982188;5180.585553155814;5188.792448302026;5194.402643696023 09:21:18 INFO - PROCESS | 7597 | |5;yui.html;613.7637009751246;640.043135512409;695.693894686338;730.3937731682704;742.5136535569201;11227.382493962828;11762.588309409162;11830.290182589051;12150.574729884456;12157.574484893008;13019.934900325508;13042.869571304283;13094.803577946315;13186.274754888478;13194.34028298586;636.4399328590833;782.1279273609913;806.1495122645621;810.5663470043528;812.1594150054694;802.9638666260004;808.4219782855257;810.5015415519442;811.2293321344716;811.6428771340592;839.7690635075342;842.0821304777791;843.9113893041234;845.7843249971248;847.3898792869124;840.4033136473081;841.1840514700762;841.6338892581686;843.7300063979529;848.9533075680835;616.6454288783922;618.2920555963397;618.9257289125288;621.878733646939;622.6637615687523;12143.696407589792;12173;12199.268043917393;12241.755164896742;12260.0804939629;10824.837627435594;10869.565217391295;10879.83680244797;11136;11435.142364322626;9907.702768916944;9933.701988940344;10058.245631577662;10107.89892101077;10206;3031.4088752693233;3078.9993252192903;3101.8914337998062;3170.8097514149226;3175.539546765706;45488;45604.54395456036;45780.77109614464;45849.08301833944;45869.08261834778;8892.73321800347;8984.730458086267;9066.410683305565;9091.409058411182;9143.222826059726;16462.917685411496;16503.83496165047;16563.50309490693;16575.585610359598;16635.584110397336;6392.968035159887;6616.470682345401;6683.933160668428;6701.29636388182;6726.96636516824;3398.2353970356967;3442.6729460701195;3500.7024402925526;3503.211777350076;3526.5768107827225;2787.6097346371525;2859.158848783617;2868.3402817351707;2879.7120287971034;2903.274181454636;483.61069339182296;484.22765688726423;487.4004973882145;487.939007624047;492.0061475818826;488.03369328729315;562.5106157642876;608.5344711295862;615.5383462403198;620.5811077522654;7368.4842061055215;7410.296021877913;7435.553866768011;7498.212687667826;7548.735794247175;497.7760007996416;529.7139544645848;536.8335815897084;592.3070008090544;598.1237487081446;3652.2695460907926;3769.754965927206;3771.905702357463;3785.091578021255;3865.2269546090893;52530.737346313785;54301.1854822178;54366.45633543614;55170;55912.440875591536;45701;45928.77035614801;46075.078498430514;46879;47611.285830712564;41656;42886.57113428826;42988.355174672404;43468.565314347084;44190.558094418644;7089.716411343695;7214.74748383804;7244.818879528054;7291.854162916606;7584.924150758642;27067.32331691645;28260.576091358234;28401.573976390784;29490.262743431587;29841.701582984762;38586;38678;38759.418608721455;38837.22325553417;39823;10374.740631483972;10556.577736890746;10621.787564248827;10683.30558513694;10713.625023124154;11052.226344155832;11230.887691122984;11313;11450;11681.591144309908;10567.63013294531;10729.409882456539;10812.783744325227;11013.614523491638;11173.441327933733;10410.479476026017;10537.209709271989;10565.20760942917;10614.8407773885;10905.890941090487;9265.861012084959;10202.38785672862;10320.277580569287;10369.63706270277;10634.09610183143;9055.366124371494;9218.262538996998;9224.40041397307;9627.470489122885;9641.710748677551;9443.338966272295;9515.143637072697;9578.664746733832;9684.95157524208;9753.170980466739 09:21:18 INFO - PROCESS | 7597 | __end_tp_report 09:21:18 INFO - PROCESS | 7597 | __start_cc_report 09:21:18 INFO - PROCESS | 7597 | _x_x_mozilla_cycle_collect,385 09:21:18 INFO - PROCESS | 7597 | __end_cc_report 09:21:18 INFO - PROCESS | 7597 | __startTimestamp1461860478854__endTimestamp 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | ------- Summary: start ------- 09:21:18 INFO - PROCESS | 7597 | Number of tests: 6 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#0] dojo.html Cycles:165 Average:4664.19 Median:4773.33 stddev:481.41 (10.1%) stddev-sans-first:454.28 09:21:18 INFO - PROCESS | 7597 | Values: 2580.2 2668.6 2674.1 2694.0 2697.1 4702.3 4724.3 4755.8 4786.4 4808.7 4597.3 4649.0 4652.4 4665.8 4670.3 4285.8 4299.7 4399.5 4484.9 4496.3 4380.4 4420.5 4424.1 4427.0 4427.0 4361.4 4398.2 4440.9 4444.4 4468.6 4290.1 4365.9 4400.6 4427.3 4428.8 4316.7 4327.7 4383.3 4405.9 4442.9 4560.4 4632.4 4663.6 4670.5 4679.3 5012.1 5025.2 5035.5 5042.2 5088.6 5032.4 5053.6 5070.1 5074.7 5089.2 4527.7 4694.7 4762.8 4764.8 4769.8 5113.5 5128.1 5132.9 5143.4 5151.5 4976.1 4977.2 4981.2 4986.3 4994.9 5028.4 5059.4 5062.2 5112.1 5145.6 4951.3 4972.6 4986.5 5005.9 5048.3 4835.4 4961.0 4984.9 4997.5 5045.7 4061.5 4179.5 4181.4 4223.2 4244.6 3630.9 3692.5 3695.7 3707.6 3710.4 4318.1 4413.0 4437.8 4438.7 4495.2 4997.3 5056.2 5068.3 5074.5 5097.2 4408.4 4461.5 4466.6 4477.1 4514.3 4363.9 4444.6 4467.7 4499.7 4525.1 4720.0 4750.2 4756.0 4757.8 4773.3 4958.5 5011.7 5015.8 5033.0 5066.7 4670.1 4731.8 4737.5 4807.7 4815.6 4996.5 5004.5 5018.1 5044.5 5044.7 5081.6 5085.4 5090.2 5111.6 5112.5 4748.9 4850.8 4896.8 4922.2 4936.6 4832.5 4846.9 4869.1 4882.8 4929.7 4690.9 4804.4 4806.2 4807.6 4873.9 4830.9 4838.0 4942.0 4979.2 5019.4 4844.6 4871.4 4888.8 4891.3 4932.9 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#1] ext.html Cycles:180 Average:43645.26 Median:19497.91 stddev:79186.51 (406.1%) stddev-sans-first:79342.60 09:21:18 INFO - PROCESS | 7597 | Values: 567.7 606.3 610.8 626.4 636.5 34346.5 34839.1 35304.0 35388.3 35400.0 36072.0 36570.0 36736.4 36761.2 36951.0 7554.2 7759.3 7804.6 7814.0 7835.6 7755.5 7839.3 7870.9 7875.8 7929.0 7804.3 7819.3 7858.6 7914.0 7929.6 7803.6 7851.9 7900.1 7904.1 7908.4 7760.6 7844.0 7874.9 7897.3 7897.7 25902.2 36451.6 36808.8 37028.3 37262.0 2845.8 2942.4 2985.1 3370.9 3595.8 27449.3 27675.2 27953.7 27978.6 28394.1 3101.9 3137.0 3151.3 3159.3 3202.5 216322.0 216976.0 217445.0 218188.0 218398.0 30964.2 32483.7 33678.3 33704.0 33770.3 32107.3 32342.5 32421.0 32443.4 32516.2 12614.0 12663.9 12764.9 12796.2 12874.6 10654.5 10712.5 10729.1 10743.1 10745.3 8186.9 8260.2 8287.4 8288.5 8314.1 3196.2 3205.6 3216.0 3268.6 3276.7 947.4 958.4 976.8 1002.5 1018.7 28451.4 28741.9 28808.4 28852.7 28940.0 985.7 986.0 991.0 1018.8 1023.1 15313.5 15431.9 15443.5 15544.2 15652.3 385936.0 392432.0 392539.0 395610.0 397955.0 50080.2 50803.7 51174.5 51271.2 51275.2 1154.2 1244.3 1269.2 1282.6 1284.0 26962.1 27812.4 27966.4 28139.9 28286.4 40075.4 41534.4 41672.6 41844.4 42039.0 258463.0 262028.0 262593.0 263232.0 264029.0 38373.2 38714.2 38800.8 38888.6 38984.0 43171.1 43301.8 43965.0 44597.8 44823.8 36423.3 37261.3 37301.8 37370.3 37617.6 36033.8 36113.3 36489.5 36582.6 36845.3 32419.8 34189.5 36619.8 36854.0 36918.3 31282.5 33281.8 33454.4 33770.0 33990.8 25046.0 25670.4 25674.0 25832.2 26030.0 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#2] jquery.html Cycles:180 Average:18323.76 Median:27205.76 stddev:38249.39 (140.6%) stddev-sans-first:38332.94 09:21:18 INFO - PROCESS | 7597 | Values: 373.5 373.7 375.5 377.4 388.5 16101.1 16255.0 17347.2 17351.3 17380.7 16128.4 16429.7 17261.9 17277.3 17315.7 5071.6 5215.7 5248.6 5259.2 5261.3 3737.3 5132.1 5643.7 5993.8 6015.5 6081.5 6082.1 6083.8 6095.5 6115.2 4937.6 5231.8 5235.8 5244.6 5246.1 5022.5 5082.5 5219.2 5408.3 5409.6 11960.8 12159.3 12191.3 12286.7 17837.3 1844.5 1849.4 1878.5 1951.5 2090.1 8975.0 9467.5 9949.0 10723.1 13720.5 1841.4 1888.2 1904.6 1951.4 2021.8 66565.3 67099.0 69988.7 74643.6 75100.2 9605.4 9782.7 9883.3 9900.9 9934.7 13591.7 13650.0 13661.2 13665.1 13724.7 7045.2 7130.8 7191.8 7341.8 7405.0 6191.2 6376.8 6468.3 6508.4 6509.5 4111.4 4233.6 4265.9 4295.9 4464.4 1764.4 1790.3 1815.8 1832.1 1839.6 849.0 901.5 987.4 998.6 1032.4 15833.1 15863.9 16695.6 16727.4 17112.8 858.9 881.3 885.6 886.5 888.0 9186.7 9510.3 9611.3 9745.7 9777.6 137864.0 138486.0 138711.0 139371.0 401744.0 72628.0 76339.0 76750.0 77252.2 80721.0 944.6 987.6 998.0 999.2 1007.9 14958.0 15014.2 15019.6 15186.5 15672.8 24785.4 31738.4 32248.8 33215.0 33863.8 45072.8 46903.5 48414.3 48695.0 49389.0 7434.3 7600.3 7639.7 7779.2 7867.8 10717.6 11276.2 11341.6 11376.7 11421.8 9784.6 9999.6 10040.6 10077.3 10102.3 9806.0 10009.5 10045.7 10150.4 10194.8 9662.1 9718.5 10080.4 10163.4 10195.6 7112.9 7411.2 7509.9 7521.2 7746.1 9586.2 9713.6 9721.1 9772.5 9800.6 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#3] mootools.html Cycles:180 Average:5083.35 Median:5168.20 stddev:2829.51 (54.7%) stddev-sans-first:2824.23 09:21:18 INFO - PROCESS | 7597 | Values: 1443.9 1473.6 1599.8 1747.6 1773.4 6792.2 7432.2 7511.5 7864.0 7866.0 7614.5 7668.5 7680.1 7699.8 7709.8 2186.7 2210.1 2210.7 2228.2 2241.1 2169.4 2201.5 2218.8 2222.6 2227.7 2184.2 2185.0 2191.6 2219.4 2247.0 2128.1 2144.6 2149.8 2151.7 2153.2 2166.7 2178.8 2184.7 2189.0 2197.2 7700.4 7706.3 7802.9 7817.6 7860.0 4405.7 4521.3 4525.9 4539.0 4540.7 2699.8 2799.4 2802.1 2830.2 2855.0 878.5 878.9 891.6 914.1 916.3 10040.3 10106.1 10108.3 10109.4 10116.9 4210.3 4236.4 4271.5 4276.0 4280.7 8698.7 8707.2 8736.2 8756.3 8776.4 4434.1 4440.7 4495.0 4497.6 4522.8 3604.7 4030.9 4079.8 4097.3 4225.2 3416.5 3473.5 3533.3 3559.3 3571.6 1200.7 1215.6 1224.4 1224.9 1236.9 734.5 879.0 885.8 891.8 895.7 5202.4 5333.9 5338.2 5384.8 5400.7 406.8 407.8 423.7 424.2 426.0 3469.6 3636.5 3651.1 3666.5 3685.1 9333.1 9694.4 9719.6 9773.0 9792.9 8942.5 9718.2 9755.8 9760.7 9777.9 7074.4 7191.9 7198.4 7206.2 7226.1 5134.0 5292.0 5327.9 5387.5 5427.8 8552.3 8640.4 8723.1 8767.9 8781.7 8604.8 8802.8 8861.6 8873.2 8923.5 6063.4 6631.1 6670.8 6747.4 6894.3 7544.9 7615.5 7626.2 7642.6 7660.5 6621.6 6724.1 6810.5 6835.9 6935.7 6692.7 6702.4 6754.8 6756.6 6775.5 6441.4 6467.4 6505.6 6521.8 6523.7 4395.1 4606.0 4623.4 4677.5 4721.1 4512.9 4656.3 4690.9 4704.9 4773.3 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#4] prototype.html Cycles:180 Average:4386.71 Median:4930.48 stddev:1291.02 (26.2%) stddev-sans-first:1257.70 09:21:18 INFO - PROCESS | 7597 | Values: 301.4 309.6 318.9 320.2 320.7 4791.0 4864.0 4886.7 4963.3 4992.1 4902.7 4925.8 4935.1 4950.3 4952.1 3935.0 3944.5 3945.2 3953.1 3965.3 3647.7 3937.3 3947.8 3953.3 3955.2 3766.5 3795.5 3796.4 3798.9 3825.7 3776.3 3802.5 3815.0 3821.1 3852.8 3333.7 3335.3 3347.6 3350.0 3357.4 4598.3 4691.3 4724.3 4760.4 4764.9 4295.0 4300.7 4337.4 4340.9 4343.5 5121.4 5133.4 5144.3 5156.8 5178.4 3476.7 3624.0 3627.5 3635.9 3646.9 5331.7 5379.9 5413.4 5421.7 5432.0 3757.9 3799.5 3801.3 3801.3 3804.7 3800.4 3822.9 3826.3 3827.5 3852.7 4231.7 4322.9 4346.5 4368.3 4375.7 5123.7 5137.9 5146.7 5148.5 5176.4 1311.6 1347.2 1387.5 1392.3 1394.7 918.3 928.9 943.9 944.0 947.4 4837.9 4842.9 4858.5 4867.3 4873.9 5602.6 5671.9 5680.8 5694.7 5707.7 4725.9 4873.2 4876.9 4895.7 4904.2 3010.9 3018.1 3033.9 3055.1 3063.8 5278.9 5280.3 5321.6 5329.8 5343.4 5499.6 5739.3 5757.7 5787.0 5809.3 5302.4 5305.3 5326.7 5332.1 5338.4 5498.0 5654.2 5664.7 5676.5 5685.7 5539.3 5547.6 5549.3 5551.6 5568.2 5292.1 5299.2 5314.9 5318.5 5336.3 3508.8 3928.2 3941.7 3944.5 3950.8 5149.9 5337.0 5354.5 5364.3 5397.4 5073.5 5202.9 5209.2 5211.6 5229.3 5161.6 5202.8 5222.3 5228.0 5238.3 4888.4 5124.5 5133.5 5168.9 5180.9 5274.7 5281.6 5294.4 5303.4 5322.6 5140.6 5168.5 5180.6 5188.8 5194.4 09:21:18 INFO - PROCESS | 7597 | 09:21:18 INFO - PROCESS | 7597 | [#5] yui.html Cycles:180 Average:12618.46 Median:45814.93 stddev:14632.45 (31.9%) stddev-sans-first:14645.72 09:21:18 INFO - PROCESS | 7597 | Values: 613.8 640.0 695.7 730.4 742.5 11227.4 11762.6 11830.3 12150.6 12157.6 13019.9 13042.9 13094.8 13186.3 13194.3 636.4 782.1 806.1 810.6 812.2 803.0 808.4 810.5 811.2 811.6 839.8 842.1 843.9 845.8 847.4 840.4 841.2 841.6 843.7 849.0 616.6 618.3 618.9 621.9 622.7 12143.7 12173.0 12199.3 12241.8 12260.1 10824.8 10869.6 10879.8 11136.0 11435.1 9907.7 9933.7 10058.2 10107.9 10206.0 3031.4 3079.0 3101.9 3170.8 3175.5 45488.0 45604.5 45780.8 45849.1 45869.1 8892.7 8984.7 9066.4 9091.4 9143.2 16462.9 16503.8 16563.5 16575.6 16635.6 6393.0 6616.5 6683.9 6701.3 6727.0 3398.2 3442.7 3500.7 3503.2 3526.6 2787.6 2859.2 2868.3 2879.7 2903.3 483.6 484.2 487.4 487.9 492.0 488.0 562.5 608.5 615.5 620.6 7368.5 7410.3 7435.6 7498.2 7548.7 497.8 529.7 536.8 592.3 598.1 3652.3 3769.8 3771.9 3785.1 3865.2 52530.7 54301.2 54366.5 55170.0 55912.4 45701.0 45928.8 46075.1 46879.0 47611.3 41656.0 42886.6 42988.4 43468.6 44190.6 7089.7 7214.7 7244.8 7291.9 7584.9 27067.3 28260.6 28401.6 29490.3 29841.7 38586.0 38678.0 38759.4 38837.2 39823.0 10374.7 10556.6 10621.8 10683.3 10713.6 11052.2 11230.9 11313.0 11450.0 11681.6 10567.6 10729.4 10812.8 11013.6 11173.4 10410.5 10537.2 10565.2 10614.8 10905.9 9265.9 10202.4 10320.3 10369.6 10634.1 9055.4 9218.3 9224.4 9627.5 9641.7 9443.3 9515.1 9578.7 9685.0 9753.2 09:21:18 INFO - PROCESS | 7597 | -------- Summary: end -------- 09:21:18 INFO - PROCESS | 7597 | 09:21:19 INFO - TEST-INFO | 7597: exit 0 09:21:19 INFO - TEST-OK | dromaeo_css | took 1116728ms 09:21:19 INFO - TEST-START | kraken 09:21:19 INFO - operating with platform_type : linux_ 09:21:19 INFO - Initialising browser for kraken test... 09:21:19 INFO - TEST-INFO | started process 7829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7kYyRC/profile http://localhost:48810/getInfo.html) 09:21:23 INFO - PROCESS | 7829 | 09:21:23 INFO - PROCESS | 7829 | (process:7897): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:21:24 INFO - PROCESS | 7829 | __metrics Screen width/height:1600/1200 09:21:24 INFO - PROCESS | 7829 | colorDepth:24 09:21:24 INFO - PROCESS | 7829 | Browser inner width/height: 1024/697 09:21:24 INFO - PROCESS | 7829 | __metrics 09:21:25 ERROR - PROCESS | 7829 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:21:25 INFO - TEST-INFO | 7829: exit 0 09:21:25 INFO - Browser initialized. 09:21:25 INFO - Running cycle 1/1 for kraken test... 09:21:25 INFO - TEST-INFO | started process 7948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7kYyRC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:27 INFO - PROCESS | 7948 | 09:21:27 INFO - PROCESS | 7948 | (process:8007): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:21:37 INFO - PROCESS | 7948 | RSS: Main: 166338560 09:21:37 INFO - PROCESS | 7948 | 09:21:40 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48810/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:41 INFO - PROCESS | 7948 | RSS: Main: 169992192 09:21:41 INFO - PROCESS | 7948 | 09:21:44 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48810/tests/kraken/driver.html?testName=audio-dft) 09:21:45 INFO - PROCESS | 7948 | RSS: Main: 173137920 09:21:45 INFO - PROCESS | 7948 | 09:21:47 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48810/tests/kraken/driver.html?testName=audio-fft) 09:21:48 INFO - PROCESS | 7948 | RSS: Main: 164220928 09:21:48 INFO - PROCESS | 7948 | 09:21:50 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48810/tests/kraken/driver.html?testName=audio-oscillator) 09:21:51 INFO - PROCESS | 7948 | RSS: Main: 165228544 09:21:51 INFO - PROCESS | 7948 | 09:21:53 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48810/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:54 INFO - PROCESS | 7948 | RSS: Main: 166699008 09:21:54 INFO - PROCESS | 7948 | 09:21:57 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48810/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:58 INFO - PROCESS | 7948 | RSS: Main: 166522880 09:21:58 INFO - PROCESS | 7948 | 09:22:01 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48810/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:02 INFO - PROCESS | 7948 | RSS: Main: 165658624 09:22:02 INFO - PROCESS | 7948 | 09:22:05 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48810/tests/kraken/driver.html?testName=json-parse-financial) 09:22:05 INFO - PROCESS | 7948 | RSS: Main: 166588416 09:22:05 INFO - PROCESS | 7948 | 09:22:07 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48810/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:08 INFO - PROCESS | 7948 | RSS: Main: 167424000 09:22:08 INFO - PROCESS | 7948 | 09:22:09 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:10 INFO - PROCESS | 7948 | RSS: Main: 167120896 09:22:10 INFO - PROCESS | 7948 | 09:22:12 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:13 INFO - PROCESS | 7948 | RSS: Main: 167374848 09:22:13 INFO - PROCESS | 7948 | 09:22:16 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:16 INFO - PROCESS | 7948 | RSS: Main: 167612416 09:22:16 INFO - PROCESS | 7948 | 09:22:19 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:20 INFO - PROCESS | 7948 | RSS: Main: 167006208 09:22:20 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | Cycle 1(1): loaded http://localhost:48810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48810/tests/kraken/driver.html?testName=ai-astar) 09:22:22 INFO - PROCESS | 7948 | RSS: Main: 168460288 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | __start_tp_report 09:22:22 INFO - PROCESS | 7948 | _x_x_mozilla_page_load 09:22:22 INFO - PROCESS | 7948 | _x_x_mozilla_page_load_details 09:22:22 INFO - PROCESS | 7948 | |i|pagename|runs| 09:22:22 INFO - PROCESS | 7948 | |0;ai-astar;113;109;115;114;114;127;113;112;114;114 09:22:22 INFO - PROCESS | 7948 | |1;audio-beat-detection;164;133;165;188;142;156;165;133;188;133 09:22:22 INFO - PROCESS | 7948 | |2;audio-dft;163;162;166;175;163;163;165;166;173;163 09:22:22 INFO - PROCESS | 7948 | |3;audio-fft;77;75;74;183;77;74;77;74;160;76 09:22:22 INFO - PROCESS | 7948 | |4;audio-oscillator;95;94;151;95;94;93;149;94;93;143 09:22:22 INFO - PROCESS | 7948 | |5;imaging-gaussian-blur;118;117;118;118;117;117;118;118;118;118 09:22:22 INFO - PROCESS | 7948 | |6;imaging-darkroom;147;147;148;148;146;148;148;147;148;147 09:22:22 INFO - PROCESS | 7948 | |7;imaging-desaturate;96;96;95;96;97;95;96;96;96;96 09:22:22 INFO - PROCESS | 7948 | |8;json-parse-financial;67;68;66;68;68;70;68;86;66;66 09:22:22 INFO - PROCESS | 7948 | |9;json-stringify-tinderbox;65;65;65;64;64;64;64;64;66;65 09:22:22 INFO - PROCESS | 7948 | |10;stanford-crypto-aes;81;79;80;81;82;79;84;80;91;83 09:22:22 INFO - PROCESS | 7948 | |11;stanford-crypto-ccm;134;138;133;139;132;134;139;134;175;135 09:22:22 INFO - PROCESS | 7948 | |12;stanford-crypto-pbkdf2;179;179;180;175;176;172;177;177;177;173 09:22:22 INFO - PROCESS | 7948 | |13;stanford-crypto-sha256-iterative;70;71;75;72;74;71;72;72;72;75 09:22:22 INFO - PROCESS | 7948 | __end_tp_report 09:22:22 INFO - PROCESS | 7948 | __start_cc_report 09:22:22 INFO - PROCESS | 7948 | _x_x_mozilla_cycle_collect,924 09:22:22 INFO - PROCESS | 7948 | __end_cc_report 09:22:22 INFO - PROCESS | 7948 | __startTimestamp1461860542451__endTimestamp 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | ------- Summary: start ------- 09:22:22 INFO - PROCESS | 7948 | Number of tests: 14 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#0] ai-astar Cycles:10 Average:114.50 Median:114.00 stddev:4.70 (4.1%) stddev-sans-first:4.95 09:22:22 INFO - PROCESS | 7948 | Values: 113.0 109.0 115.0 114.0 114.0 127.0 113.0 112.0 114.0 114.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#1] audio-beat-detection Cycles:10 Average:156.70 Median:164.50 stddev:21.22 (12.9%) stddev-sans-first:22.34 09:22:22 INFO - PROCESS | 7948 | Values: 164.0 133.0 165.0 188.0 142.0 156.0 165.0 133.0 188.0 133.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#2] audio-dft Cycles:10 Average:165.90 Median:165.50 stddev:4.51 (2.7%) stddev-sans-first:4.66 09:22:22 INFO - PROCESS | 7948 | Values: 163.0 162.0 166.0 175.0 163.0 163.0 165.0 166.0 173.0 163.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.50 stddev:40.86 (54.1%) stddev-sans-first:42.83 09:22:22 INFO - PROCESS | 7948 | Values: 77.0 75.0 74.0 183.0 77.0 74.0 77.0 74.0 160.0 76.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#4] audio-oscillator Cycles:10 Average:110.10 Median:94.00 stddev:26.01 (27.7%) stddev-sans-first:27.00 09:22:22 INFO - PROCESS | 7948 | Values: 95.0 94.0 151.0 95.0 94.0 93.0 149.0 94.0 93.0 143.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:22:22 INFO - PROCESS | 7948 | Values: 118.0 117.0 118.0 118.0 117.0 117.0 118.0 118.0 118.0 118.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 09:22:22 INFO - PROCESS | 7948 | Values: 147.0 147.0 148.0 148.0 146.0 148.0 148.0 147.0 148.0 147.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:22:22 INFO - PROCESS | 7948 | Values: 96.0 96.0 95.0 96.0 97.0 95.0 96.0 96.0 96.0 96.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:68.00 stddev:6.00 (8.8%) stddev-sans-first:6.31 09:22:22 INFO - PROCESS | 7948 | Values: 67.0 68.0 66.0 68.0 68.0 70.0 68.0 86.0 66.0 66.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:22:22 INFO - PROCESS | 7948 | Values: 65.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 66.0 65.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:81.50 stddev:3.56 (4.4%) stddev-sans-first:3.76 09:22:22 INFO - PROCESS | 7948 | Values: 81.0 79.0 80.0 81.0 82.0 79.0 84.0 80.0 91.0 83.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#11] stanford-crypto-ccm Cycles:10 Average:139.30 Median:136.50 stddev:12.79 (9.4%) stddev-sans-first:13.42 09:22:22 INFO - PROCESS | 7948 | Values: 134.0 138.0 133.0 139.0 132.0 134.0 139.0 134.0 175.0 135.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:177.00 stddev:2.59 (1.5%) stddev-sans-first:2.59 09:22:22 INFO - PROCESS | 7948 | Values: 179.0 179.0 180.0 175.0 176.0 172.0 177.0 177.0 177.0 173.0 09:22:22 INFO - PROCESS | 7948 | 09:22:22 INFO - PROCESS | 7948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:72.00 stddev:1.71 (2.4%) stddev-sans-first:1.58 09:22:22 INFO - PROCESS | 7948 | Values: 70.0 71.0 75.0 72.0 74.0 71.0 72.0 72.0 72.0 75.0 09:22:22 INFO - PROCESS | 7948 | -------- Summary: end -------- 09:22:22 INFO - PROCESS | 7948 | 09:22:23 INFO - TEST-INFO | 7948: exit 0 09:22:23 INFO - TEST-OK | kraken | took 63858ms 09:22:23 INFO - SUITE-END | took 1180s 09:22:23 INFO - Completed test suite (00:19:40) 09:22:23 INFO - javascript benchmark 09:22:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2580.238829545284, 2668.5863691127875, 2674.1309074550763, 2694.016683910373, 2697.069511018699, 4702.318163866242, 4724.267738500534, 4755.809767609296, 4786.4495583007965, 4808.687435316702, 4597.287420449836, 4648.953510464894, 4652.418447694038, 4665.76671166442, 4670.346151538779, 4285.764282964444, 4299.720518166325, 4399.472063352402, 4484.865454036368, 4496.325551167319, 4380.408644832954, 4420.491643461011, 4424.137293227822, 4426.955730442703, 4427.048184640302, 4361.4112094867105, 4398.186335527937, 4440.866773996785, 4444.400005999181, 4468.553144685522, 4290.120525292339, 4365.869023929287, 4400.559944005606, 4427.335899615051, 4428.800703968329, 4316.654667626613, 4327.697061205717, 4383.32058530925, 4405.889852753675, 4442.888927776799, 4560.429946256718, 4632.420947381577, 4663.60359369454, 4670.509596492388, 4679.274712419547, 5012.147934851084, 5025.221090730943, 5035.496450354972, 5042.243663450475, 5088.592912566985, 5032.396112466491, 5053.570446512051, 5070.08738427083, 5074.695518268915, 5089.211172268304, 4527.660425468103, 4694.6713730039255, 4762.809487620491, 4764.76176191196, 4769.833055843029, 5113.488651134857, 5128.12821820284, 5132.897342053138, 5143.4085080216255, 5151.5106064923775, 4976.129177393942, 4977.228529577921, 4981.227909674007, 4986.301917731521, 4994.850154495371, 5028.446870844205, 5059.418166910852, 5062.164742817467, 5112.130937740593, 5145.639805213674, 4951.282064100757, 4972.602191824645, 4986.501349864984, 5005.924911126336, 5048.3184770056305, 4835.443923948791, 4960.975195124001, 4984.925226121611, 4997.525235102659, 5045.672031318026, 4061.5126184858004, 4179.540250572434, 4181.435506206684, 4223.155368926227, 4244.617984381359, 3630.945535816965, 3692.5015122958594, 3695.7043436525014, 3707.629237076325, 3710.350688629533, 4318.093200427883, 4412.985013446925, 4437.755923424243, 4438.689291749611, 4495.19086564415, 4997.275395067695, 5056.216286475602, 5068.341115654941, 5074.517920797518, 5097.1844504879045, 4408.448943882015, 4461.486929003207, 4466.59800617946, 4477.104579084196, 4514.345419914094, 4363.869083927487, 4444.599986001211, 4467.7319360838455, 4499.707519011254, 4525.094981003813, 4719.9764001180465, 4750.239961606127, 4756.0, 4757.809687612456, 4773.331733557374, 4958.504149585013, 5011.699298042129, 5015.84952451427, 5032.974835125801, 5066.670666406671, 4670.136024835417, 4731.787069581808, 4737.4788773235605, 4807.663463557517, 4815.590674792682, 4996.525330093636, 5004.524570165829, 5018.071656743514, 5044.54599086084, 5044.722540260321, 5081.618878584047, 5085.364329458817, 5090.185570308733, 5111.616628752933, 5112.514311140436, 4748.88127796794, 4850.80596776125, 4896.82861099874, 4922.187839006524, 4936.555709986119, 4832.468428472795, 4846.927296090491, 4869.050535145612, 4882.82910098159, 4929.679570827885, 4690.92963605553, 4804.399450068741, 4806.182948898626, 4807.567318941312, 4873.902521949471, 4830.903381932271, 4837.95162048375, 4941.962187940573, 4979.153543897619, 5019.372578427697, 4844.612431005582, 4871.439784424837, 4888.828890988798, 4891.26631005352, 4932.8766780830765], "value": 4633.933633924316, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.6707509644405, 606.2876120558344, 610.8350745298769, 626.4080443980438, 636.5385095805539, 34346.48480272795, 34839.12902177447, 35304.0, 35388.2922341553, 35400.0, 36072.0, 36570.0, 36736.448953265724, 36761.242906782034, 36951.0, 7554.169041405455, 7759.340456061242, 7804.570748608824, 7813.9609301953415, 7835.608219589027, 7755.534667919943, 7839.294463498284, 7870.921290787105, 7875.8424831503335, 7929.048514178277, 7804.29761321481, 7819.279758453131, 7858.607069646523, 7914.0, 7929.643166057541, 7803.570803605828, 7851.939988101619, 7900.130985591581, 7904.051513818321, 7908.406869484783, 7760.611969401564, 7843.960780196063, 7874.881876771853, 7897.328727058207, 7897.723579674741, 25902.22293331203, 36451.63548364509, 36808.81595592005, 37028.25943481142, 37262.0, 2845.758110560605, 2942.382099759032, 2985.149232468758, 3370.8988730337883, 3595.8202089895663, 27449.31376715578, 27675.169744907686, 27953.720462795314, 27978.580321295198, 28394.148175554765, 3101.922451938697, 3136.952945705816, 3151.322465669892, 3159.3207460396125, 3202.455582550972, 216322.0, 216976.0, 217445.0, 218187.9999999984, 218398.0, 30964.22589435237, 32483.67516324854, 33678.32643347119, 33704.0, 33770.32459350799, 32107.307659618975, 32342.514862277083, 32421.0, 32443.35113297768, 32516.187095322806, 12613.99088072952, 12663.936680316723, 12764.872351276368, 12796.168249063781, 12874.613761587167, 10654.467276636136, 10712.46437678113, 10729.141668666643, 10743.14054875608, 10745.301555398873, 8186.918130818615, 8260.173982601811, 8287.41988060842, 8288.461250018729, 8314.127016663284, 3196.168996061041, 3205.5512228288057, 3215.9839200804313, 3268.6404495505476, 3276.6559511251453, 947.3557980573139, 958.4297343080856, 976.7753416714196, 1002.5137808163036, 1018.6689325968941, 28451.430971380458, 28741.856290718413, 28808.42383152325, 28852.711472885003, 28940.0, 985.6796541124163, 985.9795112058985, 991.038692468319, 1018.8471729240525, 1023.1405619279841, 15313.540593782205, 15431.92369124225, 15443.459478918185, 15544.222788860512, 15652.295646695926, 385936.0, 392432.0, 392539.0, 395610.0, 397955.0, 50080.24879626808, 50803.74598126986, 51174.488255117714, 51271.230931536054, 51275.23087153696, 1154.1747650429984, 1244.328062846053, 1269.238456925837, 1282.5767496726057, 1283.9679008024875, 26962.056328028426, 27812.44375112527, 27966.440671186054, 28139.859300703367, 28286.43427131487, 40075.39886901757, 41534.376984345865, 41672.58327416687, 41844.37233441562, 42039.0, 258463.0, 262028.0, 262593.0, 263232.0, 264029.0, 38373.23253534858, 38714.22571548609, 38800.80599596984, 38888.611113888495, 38984.0, 43171.13657726765, 43301.783491082344, 43965.0, 44597.77701111474, 44823.77588112169, 36423.27153456863, 37261.25477490489, 37301.81349093237, 37370.25259494849, 37617.62382376141, 36033.81983090068, 36113.27773444569, 36489.452658209615, 36582.63417365899, 36845.26309473849, 32419.837900810344, 34189.487157693155, 36619.81690091533, 36854.0, 36918.26163476769, 31282.53076203813, 33281.83359083189, 33454.4263466638, 33770.0, 33990.830045849616, 25046.0, 25670.358241044123, 25673.973041078894, 25832.225033249033, 26030.0], "value": 16297.12812836458, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.5386797305328, 373.65063665472786, 375.54746530430833, 377.3622577843446, 388.4833171881398, 16101.114438705867, 16255.0, 17347.21937512812, 17351.305947762074, 17380.652386952268, 16128.354865805382, 16429.671406571862, 17261.91369043153, 17277.30890764368, 17315.653686926253, 5071.644984851053, 5215.660982036175, 5248.6063545234065, 5259.211118332242, 5261.342332208474, 3737.3085979093817, 5132.076226279269, 5643.661380317194, 5993.820185394424, 6015.488683461889, 6081.54388420868, 6082.087686846964, 6083.756649734005, 6095.512359011268, 6115.235595550556, 4937.5556199942, 5231.790728370861, 5235.842924712227, 5244.55421289189, 5246.055709972204, 5022.522860328263, 5082.517160869711, 5219.164933610642, 5408.323959505062, 5409.6213265071465, 11960.760784784256, 12159.270443773403, 12191.268523888595, 12286.692832679255, 17837.286508539644, 1844.5019844641872, 1849.3989453427555, 1878.4834170603058, 1951.4633475794128, 2090.0594732370496, 8975.0, 9467.479288639122, 9948.95025524875, 10723.088537474325, 13720.45118195271, 1841.383136649224, 1888.2163901980662, 1904.5714714189335, 1951.443838506019, 2021.777604463508, 66565.3343466564, 67099.0, 69988.6500567494, 74643.62678186629, 75100.24899750987, 9605.423674579548, 9782.706518804447, 9883.258755593359, 9900.900990990142, 9934.652287170058, 13591.728165436636, 13650.044496885122, 13661.248631325372, 13665.111767735065, 13724.725505489834, 7045.225025247219, 7130.786076417612, 7191.849304111422, 7341.825307950702, 7404.96297518509, 6191.19514463117, 6376.84057898547, 6468.320826313264, 6508.414242718178, 6509.479241660656, 4111.403846442249, 4233.618974292329, 4265.87202383929, 4295.914081718348, 4464.352668862996, 1764.3560100563202, 1790.3375750972223, 1815.7639506864023, 1832.0931139085972, 1839.5768973135987, 848.9787755306044, 901.5041727050096, 987.3532835992293, 998.5855869814092, 1032.3547782635853, 15833.050016999017, 15863.920680396524, 16695.582610434594, 16727.414540491267, 17112.828871711125, 858.9098144694717, 881.2861582118504, 885.583775625455, 886.4814083760944, 887.9511626860456, 9186.713860059597, 9510.334276600572, 9611.279154063473, 9745.658901938397, 9777.608895644236, 137864.0, 138486.0, 138711.0, 139371.0, 401744.0, 72628.0, 76339.0, 76750.0, 77252.2274777245, 80721.0, 944.641036406175, 987.629638885418, 997.9970130019284, 999.1557134221571, 1007.8740157480315, 14958.0, 15014.174220417983, 15019.624509387353, 15186.544403667907, 15672.843271567139, 24785.380365491008, 31738.36523269568, 32248.83875580607, 33215.0, 33863.83068084644, 45072.77463612661, 46903.53096469128, 48414.27378589253, 48695.0, 49389.0, 7434.256574342522, 7600.315971562586, 7639.656215470428, 7779.222077792176, 7867.763967080997, 10717.624883129052, 11276.154288428565, 11341.603043893425, 11376.658700239006, 11421.828672569753, 9784.608615655294, 9999.55002024896, 10040.598376064876, 10077.344972576759, 10102.292839501164, 9805.950970245103, 10009.54957026921, 10045.748856278358, 10150.390976541432, 10194.796104078025, 9662.13040826329, 9718.465484398426, 10080.445575493419, 10163.44101074448, 10195.643152489627, 7112.893306600508, 7411.184769675439, 7509.88735168962, 7521.247875212435, 7746.147923728273, 9586.185174260267, 9713.611455541983, 9721.076497732704, 9772.462514561767, 9800.558974846292], "value": 7927.896663716416, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.9494617688376, 1473.6242258224154, 1599.7760313556105, 1747.606788472593, 1773.405909020478, 6792.18493780747, 7432.219616940224, 7511.474196806219, 7864.0, 7865.960670196649, 7614.4669873108905, 7668.501547399415, 7680.078390593123, 7699.846003079935, 7709.845803083935, 2186.6610675345346, 2210.0938615167784, 2210.745764237109, 2228.1533017453344, 2241.12596087526, 2169.370882444089, 2201.5486825200874, 2218.7670294619074, 2222.5999320122373, 2227.7438094619083, 2184.20276599041, 2185.0276626901036, 2191.5726433345503, 2219.3785739992827, 2247.045005872506, 2128.12746773822, 2144.5603651251454, 2149.7635260121374, 2151.741790985084, 2153.1710291537793, 2166.739991201066, 2178.7821217878245, 2184.7378314602192, 2189.0477642225524, 2197.1760589778833, 7700.422468314843, 7706.306432421109, 7802.921970780277, 7817.648205830751, 7859.96070019652, 4405.669574781872, 4521.276595744665, 4525.909481810379, 4539.001419687664, 4540.682152249344, 2699.797515186369, 2799.398129402168, 2802.1313392848283, 2830.221689035511, 2855.029290041375, 878.4816957994814, 878.872563478292, 891.5542228885557, 914.1133100892124, 916.2761418479394, 10040.347377420445, 10106.090451859369, 10108.342957707726, 10109.393436393766, 10116.898831011742, 4210.263203939298, 4236.428082208862, 4271.466066741657, 4276.013491696852, 4280.743155410685, 8698.652053917898, 8707.216350528483, 8736.213740763362, 8756.255718263892, 8776.429532080394, 4434.11317736454, 4440.71135376206, 4494.977525112354, 4497.572730590588, 4522.819087236539, 3604.747667663291, 4030.8992275193355, 4079.796010199478, 4097.282975479279, 4225.154969006211, 3416.5046068319953, 3473.5237524052177, 3533.304014073259, 3559.3059353425833, 3571.5535558055244, 1200.7478429529897, 1215.6353094071744, 1224.4204975581579, 1224.8836360545913, 1236.8886800187847, 734.5445823589408, 878.9516576588348, 885.8051228729669, 891.7636826241052, 895.6686026170358, 5202.401723801811, 5333.893322133613, 5338.199270109515, 5384.838454846361, 5400.702961337086, 406.82913176465956, 407.80425395810175, 423.6970566045292, 424.20249930131155, 426.04565772669616, 3469.5663042119736, 3636.5272514572935, 3651.1419816343137, 3666.4866918631415, 3685.09715119792, 9333.113354231336, 9694.36986595869, 9719.56261968213, 9772.95113524442, 9792.853107203398, 8942.50816205102, 9718.173955213886, 9755.756106097262, 9760.658376956773, 9777.9022209777, 7074.434045276365, 7191.89212161818, 7198.388137008308, 7206.171290301578, 7226.096737907762, 5134.0, 5292.0, 5327.9200811987075, 5387.515123638891, 5427.755750991136, 8552.315814734682, 8640.395172338127, 8723.12768723148, 8767.868481972902, 8781.692640757543, 8604.82790344177, 8802.823943521222, 8861.556922153995, 8873.245774109015, 8923.509206993678, 6063.423974722395, 6631.137952066393, 6670.833229169116, 6747.359000894907, 6894.310568943066, 7544.886826697494, 7615.4669173157345, 7626.237376262329, 7642.579658118857, 7660.463767536442, 6621.635810030494, 6724.092247546519, 6810.523263371517, 6835.897461537981, 6935.687894044881, 6692.732290708317, 6702.430293424918, 6754.797356079325, 6756.628385438848, 6775.491838112062, 6441.355864413521, 6467.353264673495, 6505.577137486048, 6521.804345869631, 6523.739050437929, 4395.1429471252795, 4605.953940460552, 4623.375844261116, 4677.46209185948, 4721.079389519148, 4512.9323060153465, 4656.301554766812, 4690.859274221779, 4704.858854234379, 4773.260144677581], "value": 4046.255879352877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.42728815251036, 309.6315384692216, 318.89954664280737, 320.18193515567714, 320.6617019044908, 4790.97604511977, 4863.951360486403, 4886.657933944621, 4963.2555116732465, 4992.126377883865, 4902.65681402302, 4925.82759603414, 4935.136351138554, 4950.282209079682, 4952.059108769331, 3934.9803250983705, 3944.5069366329208, 3945.2109578084355, 3953.1105501262273, 3965.325894597911, 3647.6717095461404, 3937.322620617979, 3947.7631342119566, 3953.268645300614, 3955.1891862168186, 3766.5291838520184, 3795.5065841440573, 3796.411556208778, 3798.924021519582, 3825.67481764049, 3776.339140650389, 3802.543694756647, 3814.999999999986, 3821.1211421372964, 3852.768833869977, 3333.683300086488, 3335.31626016666, 3347.581552305962, 3350.0, 3357.429237029686, 4598.287265473857, 4691.29630555416, 4724.338592597005, 4760.428748550196, 4764.928526072112, 4294.978525107385, 4300.65594752419, 4337.414449049369, 4340.891477713051, 4343.544912454332, 5121.359830021247, 5133.358330208724, 5144.279800827887, 5156.767945442464, 5178.404483484373, 3476.721862251014, 3624.0, 3627.5102861113673, 3635.8909232723063, 3646.927061458756, 5331.706756128411, 5379.919301210446, 5413.431589683105, 5421.728913554307, 5432.0, 3757.9436308455397, 3799.544054713452, 3801.2777572261734, 3801.334766415866, 3804.6766024887643, 3800.3539398302355, 3822.8853134406013, 3826.2921359548573, 3827.483289755847, 3852.6725228355904, 4231.724937879028, 4322.8703138905885, 4346.544497124793, 4368.301071828492, 4375.693701440869, 5123.7438128093445, 5137.8715532112, 5146.742662866842, 5148.4851514848215, 5176.352955880515, 1311.6196303072193, 1347.1512946843623, 1387.5143699705225, 1392.276016471429, 1394.6652803327129, 918.3387960668308, 928.9038934057742, 943.8820147481565, 943.9716808495756, 947.3747326764302, 4837.854864354075, 4842.927356089662, 4858.514148585184, 4867.342908707279, 4873.879007828148, 5602.579806514446, 5671.858203544862, 5680.829575112753, 5694.715264236772, 5707.6575405475805, 4725.929111063337, 4873.1959226727195, 4876.902461950741, 4895.681780684244, 4904.239842824368, 3010.864511097006, 3018.139830148441, 3033.924151896176, 3055.1292881529102, 3063.7855350125724, 5278.9472105278455, 5280.287161233261, 5321.600879934021, 5329.786808527616, 5343.358796944314, 5499.615026948155, 5739.311282646109, 5757.65454072757, 5786.971065144648, 5809.302883653989, 5302.416734159239, 5305.283786688825, 5326.707031113248, 5332.146856503018, 5338.3593968724, 5497.972510137423, 5654.15187721845, 5664.716764161858, 5676.545876329967, 5685.715714214355, 5539.307586551681, 5547.611667183259, 5549.278593782782, 5551.611387203018, 5568.248286481436, 5292.100342941632, 5299.205119232146, 5314.946850531445, 5318.547923426552, 5336.306280183551, 3508.771929824655, 3928.2339943710704, 3941.664958478447, 3944.467496887998, 3950.841966321315, 5149.897002059863, 5336.973315133399, 5354.464553544614, 5364.329458817648, 5397.379301380234, 5073.518015788494, 5202.895942081062, 5209.166533354645, 5211.55701765339, 5229.3133340993745, 5161.612879034163, 5202.843914682566, 5222.321098257202, 5227.973860130827, 5238.266642670105, 4888.413390393175, 5124.5131712487255, 5133.537981581676, 5168.9483105169975, 5180.896382072413, 5274.736263186902, 5281.55106815925, 5294.391145018372, 5303.443138470405, 5322.574194064543, 5140.614453915897, 5168.457311982188, 5180.585553155814, 5188.792448302026, 5194.402643696023], "value": 3982.3976502794153, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.7637009751246, 640.043135512409, 695.693894686338, 730.3937731682704, 742.5136535569201, 11227.382493962828, 11762.588309409162, 11830.290182589051, 12150.574729884456, 12157.574484893008, 13019.934900325508, 13042.869571304283, 13094.803577946315, 13186.274754888478, 13194.34028298586, 636.4399328590833, 782.1279273609913, 806.1495122645621, 810.5663470043528, 812.1594150054694, 802.9638666260004, 808.4219782855257, 810.5015415519442, 811.2293321344716, 811.6428771340592, 839.7690635075342, 842.0821304777791, 843.9113893041234, 845.7843249971248, 847.3898792869124, 840.4033136473081, 841.1840514700762, 841.6338892581686, 843.7300063979529, 848.9533075680835, 616.6454288783922, 618.2920555963397, 618.9257289125288, 621.878733646939, 622.6637615687523, 12143.696407589792, 12173.0, 12199.268043917393, 12241.755164896742, 12260.0804939629, 10824.837627435594, 10869.565217391295, 10879.83680244797, 11136.0, 11435.142364322626, 9907.702768916944, 9933.701988940344, 10058.245631577662, 10107.89892101077, 10206.0, 3031.4088752693233, 3078.9993252192903, 3101.8914337998062, 3170.8097514149226, 3175.539546765706, 45488.0, 45604.54395456036, 45780.77109614464, 45849.08301833944, 45869.08261834778, 8892.73321800347, 8984.730458086267, 9066.410683305565, 9091.409058411182, 9143.222826059726, 16462.917685411496, 16503.83496165047, 16563.50309490693, 16575.585610359598, 16635.584110397336, 6392.968035159887, 6616.470682345401, 6683.933160668428, 6701.29636388182, 6726.96636516824, 3398.2353970356967, 3442.6729460701195, 3500.7024402925526, 3503.211777350076, 3526.5768107827225, 2787.6097346371525, 2859.158848783617, 2868.3402817351707, 2879.7120287971034, 2903.274181454636, 483.61069339182296, 484.22765688726423, 487.4004973882145, 487.939007624047, 492.0061475818826, 488.03369328729315, 562.5106157642876, 608.5344711295862, 615.5383462403198, 620.5811077522654, 7368.4842061055215, 7410.296021877913, 7435.553866768011, 7498.212687667826, 7548.735794247175, 497.7760007996416, 529.7139544645848, 536.8335815897084, 592.3070008090544, 598.1237487081446, 3652.2695460907926, 3769.754965927206, 3771.905702357463, 3785.091578021255, 3865.2269546090893, 52530.737346313785, 54301.1854822178, 54366.45633543614, 55170.0, 55912.440875591536, 45701.0, 45928.77035614801, 46075.078498430514, 46879.0, 47611.285830712564, 41656.0, 42886.57113428826, 42988.355174672404, 43468.565314347084, 44190.558094418644, 7089.716411343695, 7214.74748383804, 7244.818879528054, 7291.854162916606, 7584.924150758642, 27067.32331691645, 28260.576091358234, 28401.573976390784, 29490.262743431587, 29841.701582984762, 38586.0, 38678.0, 38759.418608721455, 38837.22325553417, 39823.0, 10374.740631483972, 10556.577736890746, 10621.787564248827, 10683.30558513694, 10713.625023124154, 11052.226344155832, 11230.887691122984, 11313.0, 11450.0, 11681.591144309908, 10567.63013294531, 10729.409882456539, 10812.783744325227, 11013.614523491638, 11173.441327933733, 10410.479476026017, 10537.209709271989, 10565.20760942917, 10614.8407773885, 10905.890941090487, 9265.861012084959, 10202.38785672862, 10320.277580569287, 10369.63706270277, 10634.09610183143, 9055.366124371494, 9218.262538996998, 9224.40041397307, 9627.470489122885, 9641.710748677551, 9443.338966272295, 9515.143637072697, 9578.664746733832, 9684.95157524208, 9753.170980466739], "value": 5781.2011315447535, "unit": "score"}], "name": "dromaeo_css", "value": 6181.302541987305, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 115.0, 114.0, 114.0, 127.0, 113.0, 112.0, 114.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 133.0, 165.0, 188.0, 142.0, 156.0, 165.0, 133.0, 188.0, 133.0], "value": 156.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 166.0, 175.0, 163.0, 163.0, 165.0, 166.0, 173.0, 163.0], "value": 165.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 74.0, 183.0, 77.0, 74.0, 77.0, 74.0, 160.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 151.0, 95.0, 94.0, 93.0, 149.0, 94.0, 93.0, 143.0], "value": 110.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 146.0, 148.0, 148.0, 147.0, 148.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 97.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 68.0, 68.0, 70.0, 68.0, 86.0, 66.0, 66.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 66.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 80.0, 81.0, 82.0, 79.0, 84.0, 80.0, 91.0, 83.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 133.0, 139.0, 132.0, 134.0, 139.0, 134.0, 175.0, 135.0], "value": 139.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 180.0, 175.0, 176.0, 172.0, 177.0, 177.0, 177.0, 173.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 75.0, 72.0, 74.0, 71.0, 72.0, 72.0, 72.0, 75.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1607.0, "alertThreshold": 2.0}]} 09:22:23 INFO - Return code: 0 09:22:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:23 ERROR - # TBPL SUCCESS # 09:22:23 INFO - Running post-action listener: _resource_record_post_action 09:22:23 INFO - Running post-run listener: _resource_record_post_run 09:22:24 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 12288; Write bytes: 519086080; Read time: 28; Write time: 375640 09:22:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 234708992; Read time: 0; Write time: 201120 09:22:24 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 12288; Write bytes: 284377088; Read time: 28; Write time: 174520 09:22:24 INFO - Running post-run listener: _upload_blobber_files 09:22:24 INFO - Blob upload gear active. 09:22:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:24 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 09:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:22:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:22:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:26 INFO - (blobuploader) - INFO - Done attempting. 09:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:22:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:27 INFO - (blobuploader) - INFO - Done attempting. 09:22:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:27 INFO - Return code: 0 09:22:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad2c59982a499b889ea3764035e7b3edcb60de5263c0210c0d114121ae69f37dada2149f34e43800209726ea671dac203119a5266c21b380959d9db9b0392e64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aded303b679aeebe13017e5d2663aff279feb5d004ba1586472d13b15c6e4d00ac5b676fe8ce3c24bce4a2b1e0902fefd567748ec9403922d11e0b97e494a1b"} 09:22:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:27 INFO - Contents: 09:22:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad2c59982a499b889ea3764035e7b3edcb60de5263c0210c0d114121ae69f37dada2149f34e43800209726ea671dac203119a5266c21b380959d9db9b0392e64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aded303b679aeebe13017e5d2663aff279feb5d004ba1586472d13b15c6e4d00ac5b676fe8ce3c24bce4a2b1e0902fefd567748ec9403922d11e0b97e494a1b"} 09:22:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:27 INFO - Copying logs to upload dir... 09:22:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.452721 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-28 09:22:26.945963) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:22:26.949177) ========= 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=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad2c59982a499b889ea3764035e7b3edcb60de5263c0210c0d114121ae69f37dada2149f34e43800209726ea671dac203119a5266c21b380959d9db9b0392e64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aded303b679aeebe13017e5d2663aff279feb5d004ba1586472d13b15c6e4d00ac5b676fe8ce3c24bce4a2b1e0902fefd567748ec9403922d11e0b97e494a1b"} build_url:https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011355 build_url: 'https://queue.taskcluster.net/v1/task/Vk9sLMiTRIyaakAKIXUOfA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad2c59982a499b889ea3764035e7b3edcb60de5263c0210c0d114121ae69f37dada2149f34e43800209726ea671dac203119a5266c21b380959d9db9b0392e64", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5aded303b679aeebe13017e5d2663aff279feb5d004ba1586472d13b15c6e4d00ac5b676fe8ce3c24bce4a2b1e0902fefd567748ec9403922d11e0b97e494a1b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:22:26.973660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:22:26.974006) ========= 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=03627c79f6bb3d1578c4a402000001ba-1461858334.214521-9822890 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:22:27.058164) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:22:27.058480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:22:27.058787) ========= ========= Total master_lag: 0.17 =========