builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1482978216.11 results: success (0) buildid: 20161228163002 builduid: c953da4f9b6d446a815ef46b19865c29 revision: 143bb4b9249e528e658f6ccc449991794b8675f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.114988) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.115495) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.115826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.133757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.134107) ========= 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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 18:23:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-12-28 18:23:36 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118559 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.267057) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.267414) ========= 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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017226 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.325328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:36.325748) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 143bb4b9249e528e658f6ccc449991794b8675f8 --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 143bb4b9249e528e658f6ccc449991794b8675f8 --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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 18:23:36,637 truncating revision to first 12 chars 2016-12-28 18:23:36,637 Setting DEBUG logging. 2016-12-28 18:23:36,637 attempt 1/10 2016-12-28 18:23:36,638 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/143bb4b9249e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 18:23:37,084 unpacking tar archive at: mozilla-central-143bb4b9249e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.766761 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:37.109277) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:37.109618) ========= script_repo_revision: 143bb4b9249e528e658f6ccc449991794b8675f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:37.110002) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:37.110269) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 18:23:37.148640) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-28 18:23:37.149013) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:37 INFO - MultiFileLogger online at 20161228 18:23:37 in /builds/slave/test 18:23:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:23:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:37 INFO - {'append_to_log': False, 18:23:37 INFO - 'base_work_dir': '/builds/slave/test', 18:23:37 INFO - 'blob_upload_branch': 'Firefox', 18:23:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:37 INFO - 'branch': 'Firefox', 18:23:37 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:37 INFO - 'config_files': ('talos/linux_config.py',), 18:23:37 INFO - 'default_actions': ('clobber', 18:23:37 INFO - 'read-buildbot-config', 18:23:37 INFO - 'download-and-extract', 18:23:37 INFO - 'populate-webroot', 18:23:37 INFO - 'create-virtualenv', 18:23:37 INFO - 'install', 18:23:37 INFO - 'run-tests'), 18:23:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:37 INFO - 'download_minidump_stackwalk': True, 18:23:37 INFO - 'download_symbols': 'ondemand', 18:23:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:37 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:37 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:37 INFO - 'installer_path': 'installer.exe', 18:23:37 INFO - 'log_level': 'info', 18:23:37 INFO - 'log_name': 'talos', 18:23:37 INFO - 'log_to_console': True, 18:23:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:37 INFO - 'opt_config_files': (), 18:23:37 INFO - 'pip_index': False, 18:23:37 INFO - 'sps_profile': False, 18:23:37 INFO - 'sps_profile_interval': 0, 18:23:37 INFO - 'suite': 'dromaeojs-e10s', 18:23:37 INFO - 'system_bits': '32', 18:23:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:37 INFO - 'title': 'talos-linux64-ix-038', 18:23:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:37 INFO - 'use_talos_json': True, 18:23:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:37 INFO - 'work_dir': 'build'} 18:23:37 INFO - [mozharness: 2016-12-29 02:23:37.549804Z] Running clobber step. 18:23:37 INFO - Running pre-action listener: _resource_record_pre_action 18:23:37 INFO - Running main action method: clobber 18:23:37 INFO - rmtree: /builds/slave/test/build 18:23:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:38 INFO - Running post-action listener: _resource_record_post_action 18:23:38 INFO - [mozharness: 2016-12-29 02:23:38.008382Z] Finished clobber step (success) 18:23:38 INFO - [mozharness: 2016-12-29 02:23:38.008483Z] Running read-buildbot-config step. 18:23:38 INFO - Running pre-action listener: _resource_record_pre_action 18:23:38 INFO - Running main action method: read_buildbot_config 18:23:38 INFO - Using buildbot properties: 18:23:38 INFO - { 18:23:38 INFO - "project": "", 18:23:38 INFO - "product": "firefox", 18:23:38 INFO - "script_repo_revision": "production", 18:23:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:23:38 INFO - "repository": "", 18:23:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:23:38 INFO - "buildid": "20161228163002", 18:23:38 INFO - "pgo_build": "True", 18:23:38 INFO - "basedir": "/builds/slave/test", 18:23:38 INFO - "buildnumber": 504, 18:23:38 INFO - "slavename": "talos-linux64-ix-038", 18:23:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:23:38 INFO - "platform": "ubuntu64_hw", 18:23:38 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:23:38 INFO - "branch": "mozilla-central", 18:23:38 INFO - "repo_path": "mozilla-central", 18:23:38 INFO - "revision": "143bb4b9249e528e658f6ccc449991794b8675f8", 18:23:38 INFO - "stage_platform": "linux64-pgo", 18:23:38 INFO - "builduid": "c953da4f9b6d446a815ef46b19865c29", 18:23:38 INFO - "slavebuilddir": "test" 18:23:38 INFO - } 18:23:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:23:38 INFO - Running post-action listener: _resource_record_post_action 18:23:38 INFO - [mozharness: 2016-12-29 02:23:38.010906Z] Finished read-buildbot-config step (success) 18:23:38 INFO - [mozharness: 2016-12-29 02:23:38.010994Z] Running download-and-extract step. 18:23:38 INFO - Running pre-action listener: _resource_record_pre_action 18:23:38 INFO - Running main action method: download_and_extract 18:23:38 INFO - mkdir: /builds/slave/test/build/tests 18:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:38 INFO - trying https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:23:38 INFO - Downloading https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:23:38 INFO - Downloaded 1361 bytes. 18:23:38 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:23:38 INFO - Using the following test package requirements: 18:23:38 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:23:38 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:23:38 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:23:38 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'jsshell-linux-x86_64.zip'], 18:23:38 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:23:38 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:23:38 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:23:38 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:23:38 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:23:38 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:23:38 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:23:38 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:23:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:23:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:23:38 INFO - Fetch https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:23:40 INFO - Expected file size: 21836720 18:23:40 INFO - Obtained file size: 21836720 18:23:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:23:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:23:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:23:44 INFO - Fetch https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:23:44 INFO - Expected file size: 21836720 18:23:44 INFO - Obtained file size: 21836720 18:23:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:23:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:23:49 INFO - Fetch https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:23:50 INFO - Expected file size: 14409776 18:23:50 INFO - Obtained file size: 14409776 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:51 INFO - trying https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:23:51 INFO - Downloading https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:23:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:23:53 INFO - Downloaded 61371648 bytes. 18:23:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:23:53 INFO - mkdir: /builds/slave/test/properties 18:23:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:53 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:53 INFO - Contents: 18:23:53 INFO - build_url:https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:23:54 INFO - Running post-action listener: _resource_record_post_action 18:23:54 INFO - Running post-action listener: set_extra_try_arguments 18:23:54 INFO - [mozharness: 2016-12-29 02:23:54.697285Z] Finished download-and-extract step (success) 18:23:54 INFO - [mozharness: 2016-12-29 02:23:54.697551Z] Running populate-webroot step. 18:23:54 INFO - Running pre-action listener: _resource_record_pre_action 18:23:54 INFO - Running main action method: populate_webroot 18:23:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:54 INFO - '--tppagecycles', 18:23:54 INFO - '7'], 18:23:54 INFO - 'tests': ['tsvgm']}}, 18:23:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['damp', 'tps']}, 18:23:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'tests': ['damp', 'tps']}, 18:23:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['dromaeo_dom']}, 18:23:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['basic_compositor_video']}, 18:23:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:23:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['a11yr', 18:23:54 INFO - 'ts_paint', 18:23:54 INFO - 'tpaint', 18:23:54 INFO - 'sessionrestore', 18:23:54 INFO - 'sessionrestore_no_auto_restore', 18:23:54 INFO - 'tabpaint']}, 18:23:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['tsvgx', 18:23:54 INFO - 'tsvgr_opacity', 18:23:54 INFO - 'tart', 18:23:54 INFO - 'tscrollx', 18:23:54 INFO - 'cart', 18:23:54 INFO - 'tsvg_static']}, 18:23:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:54 INFO - 'tsvgr_opacity', 18:23:54 INFO - 'tart', 18:23:54 INFO - 'tscrollx', 18:23:54 INFO - 'cart', 18:23:54 INFO - 'tsvg_static']}, 18:23:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'talos_options': ['--disable-e10s'], 18:23:54 INFO - 'tests': ['tp5o']}, 18:23:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:23:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'talos_options': ['--disable-e10s', 18:23:54 INFO - '--xperf_path', 18:23:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:54 INFO - 'tests': ['tp5n']}, 18:23:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:23:54 INFO - 'talos_options': ['--xperf_path', 18:23:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:54 INFO - 'tests': ['tp5n']}}, 18:23:54 INFO - 'talos.zip': {'path': '', 18:23:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:54 INFO - Running post-action listener: _resource_record_post_action 18:23:54 INFO - [mozharness: 2016-12-29 02:23:54.715480Z] Finished populate-webroot step (success) 18:23:54 INFO - [mozharness: 2016-12-29 02:23:54.715569Z] Running create-virtualenv step. 18:23:54 INFO - Running pre-action listener: _resource_record_pre_action 18:23:54 INFO - Running main action method: create_virtualenv 18:23:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:23:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:54 INFO - Using real prefix '/usr' 18:23:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:57 INFO - Installing pip.................done. 18:23:57 INFO - Return code: 0 18:23:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:23:57 INFO - Reading from file tmpfile_stdout 18:23:57 INFO - Output received: 18:23:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:23:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:23:57 INFO - Using env: {'DISPLAY': ':0', 18:23:57 INFO - 'HOME': '/home/cltbld', 18:23:57 INFO - 'LANG': 'en_US.UTF-8', 18:23:57 INFO - 'LANGUAGE': 'en_US:en', 18:23:57 INFO - 'LOGNAME': 'cltbld', 18:23:57 INFO - 'MAIL': '/var/mail/cltbld', 18:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:57 INFO - 'MOZ_NO_REMOTE': '1', 18:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:57 INFO - 'NO_EM_RESTART': '1', 18:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:57 INFO - 'PWD': '/builds/slave/test', 18:23:57 INFO - 'SHELL': '/bin/bash', 18:23:57 INFO - 'SHLVL': '1', 18:23:57 INFO - 'TERM': 'linux', 18:23:57 INFO - 'TMOUT': '86400', 18:23:57 INFO - 'USER': 'cltbld', 18:23:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:57 INFO - '_': '/tools/buildbot/bin/python'} 18:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:23:57 INFO - Cleaning up... 18:23:57 INFO - Return code: 0 18:23:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:23:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:23:57 INFO - Using env: {'DISPLAY': ':0', 18:23:57 INFO - 'HOME': '/home/cltbld', 18:23:57 INFO - 'LANG': 'en_US.UTF-8', 18:23:57 INFO - 'LANGUAGE': 'en_US:en', 18:23:57 INFO - 'LOGNAME': 'cltbld', 18:23:57 INFO - 'MAIL': '/var/mail/cltbld', 18:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:57 INFO - 'MOZ_NO_REMOTE': '1', 18:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:57 INFO - 'NO_EM_RESTART': '1', 18:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:57 INFO - 'PWD': '/builds/slave/test', 18:23:57 INFO - 'SHELL': '/bin/bash', 18:23:57 INFO - 'SHLVL': '1', 18:23:57 INFO - 'TERM': 'linux', 18:23:57 INFO - 'TMOUT': '86400', 18:23:57 INFO - 'USER': 'cltbld', 18:23:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:57 INFO - '_': '/tools/buildbot/bin/python'} 18:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:57 INFO - Downloading/unpacking psutil>=3.1.1 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:00 INFO - Installing collected packages: psutil 18:24:00 INFO - Running setup.py install for psutil 18:24:00 INFO - building 'psutil._psutil_linux' extension 18:24:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:24:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:24:00 INFO - building 'psutil._psutil_posix' extension 18:24:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:24:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:00 INFO - Successfully installed psutil 18:24:00 INFO - Cleaning up... 18:24:00 INFO - Return code: 0 18:24:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:00 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:24:00 INFO - Using env: {'DISPLAY': ':0', 18:24:00 INFO - 'HOME': '/home/cltbld', 18:24:00 INFO - 'LANG': 'en_US.UTF-8', 18:24:00 INFO - 'LANGUAGE': 'en_US:en', 18:24:00 INFO - 'LOGNAME': 'cltbld', 18:24:00 INFO - 'MAIL': '/var/mail/cltbld', 18:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:00 INFO - 'MOZ_NO_REMOTE': '1', 18:24:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:00 INFO - 'NO_EM_RESTART': '1', 18:24:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:00 INFO - 'PWD': '/builds/slave/test', 18:24:00 INFO - 'SHELL': '/bin/bash', 18:24:00 INFO - 'SHLVL': '1', 18:24:00 INFO - 'TERM': 'linux', 18:24:00 INFO - 'TMOUT': '86400', 18:24:00 INFO - 'USER': 'cltbld', 18:24:00 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:00 INFO - '_': '/tools/buildbot/bin/python'} 18:24:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:02 INFO - Installing collected packages: mozsystemmonitor 18:24:02 INFO - Running setup.py install for mozsystemmonitor 18:24:03 INFO - Successfully installed mozsystemmonitor 18:24:03 INFO - Cleaning up... 18:24:03 INFO - Return code: 0 18:24:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:24: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 jsonschema==2.5.1 18:24:03 INFO - Using env: {'DISPLAY': ':0', 18:24:03 INFO - 'HOME': '/home/cltbld', 18:24:03 INFO - 'LANG': 'en_US.UTF-8', 18:24:03 INFO - 'LANGUAGE': 'en_US:en', 18:24:03 INFO - 'LOGNAME': 'cltbld', 18:24:03 INFO - 'MAIL': '/var/mail/cltbld', 18:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:03 INFO - 'MOZ_NO_REMOTE': '1', 18:24:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:03 INFO - 'NO_EM_RESTART': '1', 18:24:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:03 INFO - 'PWD': '/builds/slave/test', 18:24:03 INFO - 'SHELL': '/bin/bash', 18:24:03 INFO - 'SHLVL': '1', 18:24:03 INFO - 'TERM': 'linux', 18:24:03 INFO - 'TMOUT': '86400', 18:24:03 INFO - 'USER': 'cltbld', 18:24:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:03 INFO - '_': '/tools/buildbot/bin/python'} 18:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:03 INFO - Downloading/unpacking jsonschema==2.5.1 18:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:05 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:05 INFO - warning: no files found matching '*.txt' 18:24:05 INFO - no previously-included directories found matching 'build' 18:24:05 INFO - no previously-included directories found matching 'dist' 18:24:05 INFO - no previously-included directories found matching '.git*' 18:24:05 INFO - Installing collected packages: jsonschema, functools32 18:24:05 INFO - Running setup.py install for functools32 18:24:05 INFO - warning: no files found matching '*.txt' 18:24:05 INFO - no previously-included directories found matching 'build' 18:24:05 INFO - no previously-included directories found matching 'dist' 18:24:05 INFO - no previously-included directories found matching '.git*' 18:24:05 INFO - Successfully installed jsonschema functools32 18:24:05 INFO - Cleaning up... 18:24:05 INFO - Return code: 0 18:24:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:05 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:05 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:24:05 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 functools32==3.2.3-2 18:24:05 INFO - Using env: {'DISPLAY': ':0', 18:24:05 INFO - 'HOME': '/home/cltbld', 18:24:05 INFO - 'LANG': 'en_US.UTF-8', 18:24:05 INFO - 'LANGUAGE': 'en_US:en', 18:24:05 INFO - 'LOGNAME': 'cltbld', 18:24:05 INFO - 'MAIL': '/var/mail/cltbld', 18:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:05 INFO - 'MOZ_NO_REMOTE': '1', 18:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:05 INFO - 'NO_EM_RESTART': '1', 18:24:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:05 INFO - 'PWD': '/builds/slave/test', 18:24:05 INFO - 'SHELL': '/bin/bash', 18:24:05 INFO - 'SHLVL': '1', 18:24:05 INFO - 'TERM': 'linux', 18:24:05 INFO - 'TMOUT': '86400', 18:24:05 INFO - 'USER': 'cltbld', 18:24:05 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:05 INFO - '_': '/tools/buildbot/bin/python'} 18:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:06 INFO - Cleaning up... 18:24:06 INFO - Return code: 0 18:24:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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', '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 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:24: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 blobuploader==1.2.4 18:24:06 INFO - Using env: {'DISPLAY': ':0', 18:24:06 INFO - 'HOME': '/home/cltbld', 18:24:06 INFO - 'LANG': 'en_US.UTF-8', 18:24:06 INFO - 'LANGUAGE': 'en_US:en', 18:24:06 INFO - 'LOGNAME': 'cltbld', 18:24:06 INFO - 'MAIL': '/var/mail/cltbld', 18:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:06 INFO - 'MOZ_NO_REMOTE': '1', 18:24:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:06 INFO - 'NO_EM_RESTART': '1', 18:24:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:06 INFO - 'PWD': '/builds/slave/test', 18:24:06 INFO - 'SHELL': '/bin/bash', 18:24:06 INFO - 'SHLVL': '1', 18:24:06 INFO - 'TERM': 'linux', 18:24:06 INFO - 'TMOUT': '86400', 18:24:06 INFO - 'USER': 'cltbld', 18:24:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:06 INFO - '_': '/tools/buildbot/bin/python'} 18:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:06 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:08 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:08 INFO - Downloading docopt-0.6.1.tar.gz 18:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:09 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:09 INFO - Running setup.py install for blobuploader 18:24:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:09 INFO - Running setup.py install for requests 18:24:09 INFO - Running setup.py install for docopt 18:24:09 INFO - Successfully installed blobuploader requests docopt 18:24:09 INFO - Cleaning up... 18:24:09 INFO - Return code: 0 18:24:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:09 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 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:09 INFO - Using env: {'DISPLAY': ':0', 18:24:09 INFO - 'HOME': '/home/cltbld', 18:24:09 INFO - 'LANG': 'en_US.UTF-8', 18:24:09 INFO - 'LANGUAGE': 'en_US:en', 18:24:09 INFO - 'LOGNAME': 'cltbld', 18:24:09 INFO - 'MAIL': '/var/mail/cltbld', 18:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:09 INFO - 'MOZ_NO_REMOTE': '1', 18:24:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:09 INFO - 'NO_EM_RESTART': '1', 18:24:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:09 INFO - 'PWD': '/builds/slave/test', 18:24:09 INFO - 'SHELL': '/bin/bash', 18:24:09 INFO - 'SHLVL': '1', 18:24:09 INFO - 'TERM': 'linux', 18:24:09 INFO - 'TMOUT': '86400', 18:24:09 INFO - 'USER': 'cltbld', 18:24:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:09 INFO - '_': '/tools/buildbot/bin/python'} 18:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:09 INFO - Running setup.py (path:/tmp/pip-HW9ywL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:09 INFO - Running setup.py (path:/tmp/pip-EFdrOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:10 INFO - Running setup.py (path:/tmp/pip-DHNUTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:10 INFO - Running setup.py (path:/tmp/pip-gPEW0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:10 INFO - Running setup.py (path:/tmp/pip-pU7eXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:10 INFO - Running setup.py (path:/tmp/pip-zPGt4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 INFO - Running setup.py (path:/tmp/pip-imzPx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:10 INFO - Running setup.py (path:/tmp/pip-RF12tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:10 INFO - Running setup.py (path:/tmp/pip-68NufM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:10 INFO - Running setup.py (path:/tmp/pip-qoO4rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:10 INFO - Running setup.py (path:/tmp/pip-3GYgcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:10 INFO - Running setup.py (path:/tmp/pip-CkJMQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:10 INFO - Running setup.py (path:/tmp/pip-lCxouu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:10 INFO - Running setup.py (path:/tmp/pip-Zr7Hxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:10 INFO - Running setup.py (path:/tmp/pip-wcUZ2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:10 INFO - Running setup.py (path:/tmp/pip-5Plg3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:11 INFO - Running setup.py (path:/tmp/pip-YuNEez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:11 INFO - Running setup.py install for manifestparser 18:24:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Running setup.py install for mozcrash 18:24:11 INFO - Running setup.py install for mozdebug 18:24:11 INFO - Running setup.py install for mozdevice 18:24:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Running setup.py install for mozfile 18:24:11 INFO - Running setup.py install for mozhttpd 18:24:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Running setup.py install for mozinfo 18:24:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Running setup.py install for mozInstall 18:24:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:11 INFO - Running setup.py install for mozleak 18:24:11 INFO - Running setup.py install for mozlog 18:24:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Running setup.py install for moznetwork 18:24:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Running setup.py install for mozprocess 18:24:12 INFO - Running setup.py install for mozprofile 18:24:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Running setup.py install for mozrunner 18:24:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Running setup.py install for mozscreenshot 18:24:12 INFO - Running setup.py install for moztest 18:24:12 INFO - Running setup.py install for mozversion 18:24:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:12 INFO - Cleaning up... 18:24:12 INFO - Return code: 0 18:24:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:12 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 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:12 INFO - Using env: {'DISPLAY': ':0', 18:24:12 INFO - 'HOME': '/home/cltbld', 18:24:12 INFO - 'LANG': 'en_US.UTF-8', 18:24:12 INFO - 'LANGUAGE': 'en_US:en', 18:24:12 INFO - 'LOGNAME': 'cltbld', 18:24:12 INFO - 'MAIL': '/var/mail/cltbld', 18:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:12 INFO - 'MOZ_NO_REMOTE': '1', 18:24:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:12 INFO - 'NO_EM_RESTART': '1', 18:24:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:12 INFO - 'PWD': '/builds/slave/test', 18:24:12 INFO - 'SHELL': '/bin/bash', 18:24:12 INFO - 'SHLVL': '1', 18:24:12 INFO - 'TERM': 'linux', 18:24:12 INFO - 'TMOUT': '86400', 18:24:12 INFO - 'USER': 'cltbld', 18:24:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:12 INFO - '_': '/tools/buildbot/bin/python'} 18:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:13 INFO - Running setup.py (path:/tmp/pip-QUTD4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:13 INFO - Running setup.py (path:/tmp/pip-oyJdjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:13 INFO - Running setup.py (path:/tmp/pip-JGsxMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:13 INFO - Running setup.py (path:/tmp/pip-tRDuH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:13 INFO - Running setup.py (path:/tmp/pip-TxEBw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:13 INFO - Running setup.py (path:/tmp/pip-PYy9nP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:13 INFO - Running setup.py (path:/tmp/pip-o01XPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:13 INFO - Running setup.py (path:/tmp/pip-dtp62g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:13 INFO - Running setup.py (path:/tmp/pip-t_8_Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:13 INFO - Running setup.py (path:/tmp/pip-COcRq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:13 INFO - Running setup.py (path:/tmp/pip-ycMOmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:13 INFO - Running setup.py (path:/tmp/pip-rMP_ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:13 INFO - Running setup.py (path:/tmp/pip-74lc8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:14 INFO - Running setup.py (path:/tmp/pip-FcmlX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:14 INFO - Running setup.py (path:/tmp/pip-kbQIxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:14 INFO - Running setup.py (path:/tmp/pip-4KiGrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:14 INFO - Running setup.py (path:/tmp/pip-GTvN0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:24:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:14 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:16 INFO - Downloading blessings-1.6.tar.gz 18:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:16 INFO - Installing collected packages: blessings 18:24:16 INFO - Running setup.py install for blessings 18:24:16 INFO - Successfully installed blessings 18:24:16 INFO - Cleaning up... 18:24:16 INFO - Return code: 0 18:24:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:16 INFO - Reading from file tmpfile_stdout 18:24:16 INFO - Current package versions: 18:24:16 INFO - argparse == 1.2.1 18:24:16 INFO - blessings == 1.6 18:24:16 INFO - blobuploader == 1.2.4 18:24:16 INFO - docopt == 0.6.1 18:24:16 INFO - functools32 == 3.2.3-2 18:24:16 INFO - jsonschema == 2.5.1 18:24:16 INFO - manifestparser == 1.1 18:24:16 INFO - mozInstall == 1.12 18:24:16 INFO - mozcrash == 1.0 18:24:16 INFO - mozdebug == 0.1 18:24:16 INFO - mozdevice == 0.49 18:24:16 INFO - mozfile == 1.2 18:24:16 INFO - mozhttpd == 0.7 18:24:16 INFO - mozinfo == 0.9 18:24:16 INFO - mozleak == 0.1 18:24:16 INFO - mozlog == 3.4 18:24:16 INFO - moznetwork == 0.27 18:24:16 INFO - mozprocess == 0.23 18:24:16 INFO - mozprofile == 0.28 18:24:16 INFO - mozrunner == 6.13 18:24:16 INFO - mozscreenshot == 0.1 18:24:16 INFO - mozsystemmonitor == 0.3 18:24:16 INFO - moztest == 0.7 18:24:16 INFO - mozversion == 1.4 18:24:16 INFO - psutil == 3.1.1 18:24:16 INFO - requests == 1.2.3 18:24:16 INFO - wsgiref == 0.1.2 18:24:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:16 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 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:16 INFO - Using env: {'DISPLAY': ':0', 18:24:16 INFO - 'HOME': '/home/cltbld', 18:24:16 INFO - 'LANG': 'en_US.UTF-8', 18:24:16 INFO - 'LANGUAGE': 'en_US:en', 18:24:16 INFO - 'LOGNAME': 'cltbld', 18:24:16 INFO - 'MAIL': '/var/mail/cltbld', 18:24:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:16 INFO - 'MOZ_NO_REMOTE': '1', 18:24:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:16 INFO - 'NO_EM_RESTART': '1', 18:24:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:16 INFO - 'PWD': '/builds/slave/test', 18:24:16 INFO - 'SHELL': '/bin/bash', 18:24:16 INFO - 'SHLVL': '1', 18:24:16 INFO - 'TERM': 'linux', 18:24:16 INFO - 'TMOUT': '86400', 18:24:16 INFO - 'USER': 'cltbld', 18:24:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:16 INFO - '_': '/tools/buildbot/bin/python'} 18:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:24:17 INFO - Cleaning up... 18:24:17 INFO - Return code: 0 18:24:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:24:17 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 0x211c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x248dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, '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': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:24:17 INFO - Using env: {'DISPLAY': ':0', 18:24:17 INFO - 'HOME': '/home/cltbld', 18:24:17 INFO - 'LANG': 'en_US.UTF-8', 18:24:17 INFO - 'LANGUAGE': 'en_US:en', 18:24:17 INFO - 'LOGNAME': 'cltbld', 18:24:17 INFO - 'MAIL': '/var/mail/cltbld', 18:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:17 INFO - 'MOZ_NO_REMOTE': '1', 18:24:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:17 INFO - 'NO_EM_RESTART': '1', 18:24:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:17 INFO - 'PWD': '/builds/slave/test', 18:24:17 INFO - 'SHELL': '/bin/bash', 18:24:17 INFO - 'SHLVL': '1', 18:24:17 INFO - 'TERM': 'linux', 18:24:17 INFO - 'TMOUT': '86400', 18:24:17 INFO - 'USER': 'cltbld', 18:24:17 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:17 INFO - '_': '/tools/buildbot/bin/python'} 18:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:17 INFO - Cleaning up... 18:24:17 INFO - Return code: 0 18:24:17 INFO - Running post-action listener: _resource_record_post_action 18:24:17 INFO - Running post-action listener: _start_resource_monitoring 18:24:17 INFO - Starting resource monitoring. 18:24:17 INFO - [mozharness: 2016-12-29 02:24:17.360222Z] Finished create-virtualenv step (success) 18:24:17 INFO - [mozharness: 2016-12-29 02:24:17.360716Z] Running install step. 18:24:17 INFO - Running pre-action listener: _resource_record_pre_action 18:24:17 INFO - Running main action method: install 18:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:17 INFO - Reading from file tmpfile_stdout 18:24:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:17 INFO - Reading from file tmpfile_stdout 18:24:17 INFO - Output received: 18:24:17 INFO - Usage: mozinstall [options] installer 18:24:17 INFO - Options: 18:24:17 INFO - -h, --help show this help message and exit 18:24:17 INFO - -d DEST, --destination=DEST 18:24:17 INFO - Directory to install application into. [default: 18:24:17 INFO - "/builds/slave/test"] 18:24:17 INFO - --app=APP Application being installed. [default: firefox] 18:24:17 INFO - mkdir: /builds/slave/test/build/application 18:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:24:34 INFO - Reading from file tmpfile_stdout 18:24:34 INFO - Output received: 18:24:34 INFO - /builds/slave/test/build/application/firefox/firefox 18:24:34 INFO - Running post-action listener: _resource_record_post_action 18:24:34 INFO - [mozharness: 2016-12-29 02:24:34.984192Z] Finished install step (success) 18:24:34 INFO - [mozharness: 2016-12-29 02:24:34.984435Z] Running run-tests step. 18:24:34 INFO - Running pre-action listener: _resource_record_pre_action 18:24:34 INFO - Running main action method: run_tests 18:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:24:34 INFO - Python 2.7.3 18:24:34 INFO - Return code: 0 18:24:34 INFO - grabbing minidump binary from tooltool 18:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248b3f0>, '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 0x248bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x248c1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:24:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:24:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:24:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:24:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:24:35 INFO - Return code: 0 18:24:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:24:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:24:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:24:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:24:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:24:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:24:35 INFO - Using env: {'DISPLAY': ':0', 18:24:35 INFO - 'HOME': '/home/cltbld', 18:24:35 INFO - 'LANG': 'en_US.UTF-8', 18:24:35 INFO - 'LANGUAGE': 'en_US:en', 18:24:35 INFO - 'LOGNAME': 'cltbld', 18:24:35 INFO - 'MAIL': '/var/mail/cltbld', 18:24:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:24:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:35 INFO - 'MOZ_NO_REMOTE': '1', 18:24:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:35 INFO - 'NO_EM_RESTART': '1', 18:24:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:35 INFO - 'PWD': '/builds/slave/test', 18:24:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:24:35 INFO - 'SHELL': '/bin/bash', 18:24:35 INFO - 'SHLVL': '1', 18:24:35 INFO - 'TERM': 'linux', 18:24:35 INFO - 'TMOUT': '86400', 18:24:35 INFO - 'USER': 'cltbld', 18:24:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075', 18:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:35 INFO - '_': '/tools/buildbot/bin/python'} 18:24:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:24:35 INFO - mozversion application_buildid: 20161228163002 18:24:35 INFO - mozversion application_changeset: 143bb4b9249e528e658f6ccc449991794b8675f8 18:24:35 INFO - mozversion application_display_name: Nightly 18:24:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:24:35 INFO - mozversion application_name: Firefox 18:24:35 INFO - mozversion application_remotingname: firefox 18:24:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:24:35 INFO - mozversion application_vendor: Mozilla 18:24:35 INFO - mozversion application_version: 53.0a1 18:24:35 INFO - mozversion platform_buildid: 20161228163002 18:24:35 INFO - mozversion platform_changeset: 143bb4b9249e528e658f6ccc449991794b8675f8 18:24:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:24:35 INFO - mozversion platform_version: 53.0a1 18:24:35 INFO - using testdate: 1482978275 18:24:35 INFO - actual date: 1482978275 18:24:35 INFO - starting webserver on 'localhost:40865' 18:24:35 INFO - SUITE-START | Running 2 tests 18:24:35 INFO - TEST-START | dromaeo_css 18:24:35 INFO - operating with platform_type : linux_ 18:24:35 INFO - Initialising browser for dromaeo_css test... 18:24:35 INFO - TEST-INFO | started process 27723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzagTQp/profile http://localhost:40865/getInfo.html) 18:24:35 INFO - PROCESS | 27723 | 1482978275740 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 18:24:40 INFO - PROCESS | 27723 | __metrics Screen width/height:1600/1200 18:24:40 INFO - PROCESS | 27723 | colorDepth:24 18:24:40 INFO - PROCESS | 27723 | Browser inner width/height: 1024/697 18:24:40 INFO - PROCESS | 27723 | __metrics 18:24:40 ERROR - PROCESS | 27723 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 18:24:42 INFO - TEST-INFO | 27723: exit 0 18:24:42 INFO - Browser initialized. 18:24:42 INFO - Running cycle 1/1 for dromaeo_css test... 18:24:42 INFO - TEST-INFO | started process 27845 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzagTQp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:53 INFO - PROCESS | 27845 | RSS: Main: 154718208 18:24:53 INFO - PROCESS | 27845 | 18:27:54 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40865/tests/dromaeo/cssquery-ext.html) 18:27:55 INFO - PROCESS | 27845 | RSS: Main: 164339712 18:27:55 INFO - PROCESS | 27845 | 18:29:42 INFO - PROCESS | 27845 | ************************* 18:29:42 INFO - PROCESS | 27845 | A coding exception was thrown and uncaught in a Task. 18:29:42 INFO - PROCESS | 27845 | 18:29:42 INFO - PROCESS | 27845 | Full message: ReferenceError: fetch is not defined 18:29:42 INFO - PROCESS | 27845 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 18:29:42 INFO - PROCESS | 27845 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 18:29:42 INFO - PROCESS | 27845 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 18:29:42 INFO - PROCESS | 27845 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:29:42 INFO - PROCESS | 27845 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:29:42 INFO - PROCESS | 27845 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:29:42 INFO - PROCESS | 27845 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 18:29:42 INFO - PROCESS | 27845 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:29:42 INFO - PROCESS | 27845 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:29:42 INFO - PROCESS | 27845 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:29:42 INFO - PROCESS | 27845 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 18:29:42 INFO - PROCESS | 27845 | 18:29:42 INFO - PROCESS | 27845 | ************************* 18:30:57 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-ext.html (next: http://localhost:40865/tests/dromaeo/cssquery-jquery.html) 18:30:58 INFO - PROCESS | 27845 | RSS: Main: 161476608 18:30:58 INFO - PROCESS | 27845 | 18:34:00 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40865/tests/dromaeo/cssquery-mootools.html) 18:34:00 INFO - PROCESS | 27845 | RSS: Main: 165027840 18:34:00 INFO - PROCESS | 27845 | 18:37:02 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40865/tests/dromaeo/cssquery-prototype.html) 18:37:03 INFO - PROCESS | 27845 | RSS: Main: 160415744 18:37:03 INFO - PROCESS | 27845 | 18:40:05 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40865/tests/dromaeo/cssquery-yui.html) 18:40:06 INFO - PROCESS | 27845 | RSS: Main: 165711872 18:40:06 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | Cycle 1(1): loaded http://localhost:40865/tests/dromaeo/cssquery-yui.html (next: http://localhost:40865/tests/dromaeo/cssquery-dojo.html) 18:43:09 INFO - PROCESS | 27845 | RSS: Main: 164392960 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | __start_tp_report 18:43:09 INFO - PROCESS | 27845 | _x_x_mozilla_page_load 18:43:09 INFO - PROCESS | 27845 | _x_x_mozilla_page_load_details 18:43:09 INFO - PROCESS | 27845 | |i|pagename|runs| 18:43:09 INFO - PROCESS | 27845 | |0;dojo.html;3181.809091454513;3208.4385232584305;3221.774475786696;3243.1243564237657;3244.1403028197547;5358.571314294857;5411.621186516945;5444.972775136123;5450.1552259399805;5467.699276539789;5414.620976531644;5415.918761218575;5482.972585137079;5507.586930980173;5563.13771365438;4902.117618828618;4907.484714105021;4956.355673762406;5004.999000199975;5041.294218809369;4908.779104940286;5035.471275516073;5062.392512898457;5080.542751152396;5104.132297509414;4867.805287788484;4994.900101997976;5037.949620503785;5048.419431765321;5064.924026139611;4871.951280487221;4886.364772579577;4897.828575999859;4913.091078150518;5011.3485246918035;4866.27449809391;4887.535684110004;4890.877728056791;4903.166461701484;4917.901641967141;5389.326334208224;5404.5676345892625;5423.078076726966;5433.646812957185;5434.673919564839;5626.071698169797;5667.489925906647;5792.247007888989;5810.33181184165;5828.184054232411;5907.113932910055;5907.350191478934;5918;5918.556108291896;5952.940470595282;5470.370907345667;5478.808241711561;5479.0959491683425;5481.972590137024;5486.972565137149;5754.482096611241;5804.709764511757;5883.117532370179;5932.911006334908;5990.820275391745;5716.485516303553;5723.828285151452;5743.971280143573;5766.192733017381;5777.942220577825;5756.913646295309;5782.797602083907;5827.854303642358;5844.15259787337;5864.589478736448;5624.493795558419;5663.858403539944;5793.275840519935;5820.35976042635;5837.708114594253;5852.326982397079;5872.706364681749;5885.793997210077;5897.734601942837;5939.07944268639;4366.126774645084;4372.934405983913;4374.518802931675;4377.146456440963;4408.7575183365225;3805.1058001369656;3822.9426558601085;3841.635044670752;3894.240623078529;3945.3687410014836;5578.86052848682;5619.80330688424;5651.689157096399;5659.745311460994;5660;5834.387389324144;5881.529477641779;5889.852753681106;5891.764329426775;5904.380040095728;5368.114261146867;5510.559155267569;5573.860653483695;5623;5623.887522249532;4797.688150270221;4843.564079232816;4853.684510506806;4879.512048795092;4923.90152196954;6129.693515324305;6155.569110162335;6162.969185154046;6169.074638804215;6178.752849885954;5674.432556744293;5876.941230587639;5879.176915231957;5882.1470886722045;5904.9704751475965;5916.585838991229;6061.36355682647;6070.575059745952;6088.786892458743;6164.691765411621;5748.626339287933;5788;5792.971035144966;5804.6807425592;5826.854328641818;5851.0053290939795;5867.882642347044;5879.147523608967;5879.2944846616965;5912.054071348563;5585.776568937359;5647.265855438766;5651.547876169979;5664.716764161858;5703.116017017369;5696.145578163143;5698.629589076697;5698.886022279448;5733.483986441241;5738.196652468574;5565.387807341108;5723.055695810312;5731.197632331394;5734.139879018181;5735.913961290667;5636.154576813511;5691.430856914276;5704.11586204139;5712.08606622955;5803.912941305968;5533.3083364579425;5591.412901645269;5619.971900140473;5621.606487545832;5641.520470759867 18:43:09 INFO - PROCESS | 27845 | |1;ext.html;545.0108053882203;552.2710022769941;560.7840981222231;574.107263205715;584.6229182177495;40041.79979100104;40982.795086024526;41118.588814111885;44943.77528112355;45106.54893451065;42137.15725685484;42476.57523424773;42555.57444425555;42617.360739588934;42618.360724589154;8932.776680582989;9092.090790920922;9113.635454581809;9116.498592577405;10092.646757363495;10063.194944404466;10411.895881041168;10430.269881108325;10449.58201671936;10454.52954617044;10496.212784041189;10511.421871797049;10558;10569.894301056967;10571.260011799177;10390.428526431044;10433.16534677224;10445.26883118182;10449.26855120142;10462.738431539272;10116.797664046679;10246.385216886936;10262.640807571774;10277.229207809369;10327.431991240479;36308.455373169425;36764;36766.632333676585;36907;36918.815405923066;3993.1483247853707;4004.979975100106;4061.3095773718537;4109.2808758467145;4144.79276036197;28089.85955070232;28378.858105709547;28481.28796780097;28773.280667983257;28814.567781483296;4231.936520952188;4360.345948107809;4399.5160532341415;4417.712848664826;4422.204003279408;325771;328364;328862;330563.9999999976;331053;50473;50731.74634126806;50733.74633126811;50809.237861432106;50881.74559127255;54083;54140.72929635327;54202.72898635561;54260;54327;14103.2948352584;14441.85558144426;14446.422143114369;14474.203918784568;14501.419943202154;9824.852627210597;9876.35803672759;10281.177505799518;10773.138148948065;10832.512536935857;16081.678366432607;16178.19109044543;16181.919090404708;16233.727278181064;16298.67402651964;6657.23441804189;6658.600483970978;6695.966520167465;6753.797386078426;6815.829604259834;827.4662842466696;827.664795757719;849.0872312264217;854.8418542569644;865.6970060478782;27101.322466938484;27880.72119278822;28409;29651.703482964895;31220.102270853153;1023.4166525080782;1032.850236715672;1037.6368271105052;1055.5408397347178;1069.25152393325;13882.08476219985;14261.42954281817;14372.712545749235;14554.636134096521;14814.777778333333;495807;496506;498054;498058;498429;50853.23720144201;51121.233181502306;51332.48667513352;51357;51539.48460515422;1315.5001099582098;1334.8998825088158;1368.056041331498;1372.5813626843708;1373.6565858535366;26667.33331666724;26778.330541736614;26929;27706;27796.30175813913;43526.782366087966;44191;44287.11425771402;44426;44470.55529444664;346787;347549;348486;348777;349725;24779.50440991206;24961.50538924588;25642.487150257264;25738.742612573635;27151.86424067867;40185.598144018186;40316;40438.79780601078;41418.40851485011;41686.58313416827;34306;35109.47335789914;35195.472067919516;35286.647133528335;35304;34243.31513369669;34447.4832877512;34563.30873382468;34607.4808877862;34674.82662586772;34354.312913742084;35064.12339691426;35187.472187917716;35261.11847203738;35393.82303088468;28844.711552884204;31322.21694457657;31337;31519.52720709237;31697.8415107923;24271.635925461487;24275.150369736974;24459.633105503075;24483.265502034967;25010.124645637316 18:43:09 INFO - PROCESS | 27845 | |2;jquery.html;399.2573812708364;401.7328476563086;410.5607000509455;412.54619918090106;426.3562021347766;15017.6996460071;17313.394031208896;18303.90848045758;18790.718139227887;18833.811661883377;18088.36690715825;18615.348462803806;18750.156242969064;18848.62302753944;19079.904600477013;5113.079645663779;5217.113090774577;5576.972115139419;5688.829335119953;5720.427957204267;5623.578231632623;5645.435456454363;5681.176229446728;5698.572607054467;5701.6864072476;5642.379338272786;5671.2060311556415;5885.970570147165;5917.142014407908;5970.552208584373;5794.5074668653215;5888.381719919411;5919.704014799243;5932.644041357532;5936.762529498815;5754.510866576346;5784.13238014297;5799.681017544033;5803.7098145093;5806.622569532967;13477.797833032513;13565.864341356559;20916.163353465843;21841.453963650878;21995.890020549956;2831.6177316062276;3005.7144571265694;3024.4555979923607;3025.2588115911744;3035.893743718981;9688.267108388056;9967.252456065824;9990.850137247948;10055.748606284827;10146.644867429679;1882.1530311359943;1998.8700734452214;2105.799949004842;2177.096504950444;2185.158713895146;74470;76146.6192669033;78013.60993195111;78236;81148.18851811525;10308.175345972304;10782.946085269523;10801.83797243042;10973.067289280501;11215;12007.279563226235;15270.465533706489;15288.847111528965;15351.53945381618;15426.691466170614;7304.671289791972;7334.9266507335315;7429.85140297191;7581.583012934341;7583.431242656823;7136.429085673134;7238.457115716343;7375.741849035258;7395.223501532261;7420.369268612229;3927.7446965947124;4000.8399664013696;4060.3503439449546;4155.688323375759;4158.08522125132;1934.139308007951;1970.6255811395788;2024.2611446821804;2028.8782673039666;2034.7253120828796;876.9298456123495;887.671561522241;895.4403497813867;896.3591032411726;922.7831459606987;17320.39378621742;17361;17487;17678.823211767976;18448.446546603354;898.6046139698511;908.52756566585;921.0559176843789;923.5797712041006;940.2008292951074;9931.205503559842;10238.389210751211;10368.740781480372;10427.11369533583;10491.68524944253;404587;421523;425923;426697;427457;74211.62894185494;75663.62168189124;78458.60770696224;79624.20375796284;80400;945.0738276488942;966.6713317472094;988.3328753091635;988.4316518002178;996.5814357969671;15667.686646266782;15907.363705451653;15978.360865565248;16296.511104666879;16386.918065409598;37062.25875482529;38211.80894095623;38511.61488385192;38557.614423855404;38691.41962870616;50251;50398.74800625973;50852.745736271085;52964;53543;7948.125706172432;8073.7577872663915;8080.595970201584;8223.465474744122;8229.218224268685;10209.336393134423;11819.763604728028;12073.154879158727;12132.514699412277;12316.692082697646;10511.684649460529;10562.577496900345;10729.731756705833;10730.195235357223;10903.563857445613;10454.68635940923;10730.463476825971;10783.892161078602;10864.565417383215;11023.393713345842;10116.696499105039;10146.644867429899;10772.784544309226;10791.946040269748;11214.719632009264;8132.3494120471405;8294.958525207336;8341.24928756415;8393.83212335762;8432.789180270296;10479.423631700025;10772.946135269274;10889.183311251529;10918.126549875831;11204.887951120385 18:43:09 INFO - PROCESS | 27845 | |3;mootools.html;1314.093275639809;1327.4292054416603;1341.510348722716;1358.7622166120927;1362.489066600025;7636.160022397532;7916.40626952978;8123;8552.615132319044;8588.828223435536;8092.433529652927;8292.70975515857;8347.332213422927;8592.441491303076;8597.1402859714;2323.442373830277;2371.6561098640686;2374.169040835711;2374.964375534368;2385.451346190377;2290.9885450572806;2309.9653505197434;2354.387859156623;2372.6915500984846;2388.474535602165;2335.532893421314;2363.054778088761;2364.8226383021256;2366.4675448024227;2369.5853225685523;2277.0322612889454;2279.5782780185637;2294.380517260339;2301.6317389217643;2306.215886598556;2265.4676151104477;2278.100150440583;2283.2807665585287;2294.552562250362;2350.82368822339;8258.504489730576;8330.583470826434;8551.144885511461;8677.783055423602;8682.739517814476;5178.870528236786;5252.290940722992;5256.8685782855355;5261.316028916216;5274.762635681365;3149.165471150147;3291.8683252669866;3336.6830151135605;3343.297907439416;3351.2962277921665;1411.1110000699493;1467.78717086023;1474.329180222996;1510.644998425369;1573.6065983504125;11707.180497365187;11777.94111029448;11850.822237666442;11861.406929653484;11888.643340699706;4443.555644435531;4488.573585509372;4542.273236282179;4562.338460923148;4633.745144017044;9168.387009702203;9171.954140229256;9276.536173191313;9301.255899528021;9315.115064068903;5132.589392848563;5251.842444726664;5268.710220937885;5271.762770675329;5309.070912590359;4760.857174284777;4807.038592281558;4859.465458799529;4908.361912951294;4916.950830491721;3593.9101522462147;3600.801955892451;3681.815909204529;3712.3132220539287;3795.7153213508545;1100.9174311926638;1127.0983213429224;1132.2017977325966;1175.9941200293945;1216.9817452738216;939.3189937295406;950.5199874063553;958.4680502321279;960.4957397366298;969.3263182088381;5308.097623404031;5543.61194716366;5562.972185139049;5815.767369305265;5823.097419899837;909.8908131024187;918.4672889723944;922.6632279218171;922.9630814767335;924.542351535994;3715.20123173519;3738.12154143776;3801.581825999138;3810.3522401191863;3874.8837534873437;9993.350432221881;10236.590536378462;10296.433696146783;10322.638707645196;10323.63867264642;10088.69733907984;10188.184945204366;10202.897971020344;10223.488825558841;10276;7552.358049565739;7676.270754278335;7809.80475488106;7830.060392752906;7964.681412743541;5707.971460142673;5852.356240813422;5885.234919460443;5956.076808094752;6105.9694701526205;9445.260874346637;9514.904850951401;9535.33252672334;9663;9668.75828104303;9479.336446448959;9543.809123817624;9610.855837162308;9647.421154730739;9663.806723865624;7180.820479487846;7347.55914645123;7431.479796414199;7519.135299440634;7646.120696119795;8198.631061602124;8230.753077407688;8305.211004954519;8307;8324.875126872981;7425.146108197627;7457.291557302047;7491.662875170521;7623.542587444771;7679.692812287446;6984.371406573433;7382.224866388952;7401.185869554237;7414.666340014604;7466.477346585904;6253.280872699515;6435.420812126931;6452.483801295979;6484.157059582224;6507.12153859223;4822.324874517641;4833.202521583899;4919.385076865392;4952.826651067196;5176.50823171798;4760.714357138583;4852.781624826821;4855.8057677692495;4864.513548645107;4883.876708357169 18:43:09 INFO - PROCESS | 27845 | |4;prototype.html;384.8325978199483;396.1206122408254;397.7275566237127;399.3769719237987;404.7369210013493;5237.397699264586;5398.64908780929;5481.808136715215;5552.333719953601;5605.934872374246;5323.1749078892735;5389;5399.9730001349935;5417.945820541793;5445.646033007852;4318.244307246235;4320.135972805436;4321.330193819963;4322.2003929273105;4368.89077773055;4122.9381559276635;4250.319948808191;4355.54089380058;4356.411884395614;4357.716748411359;4189.97905010476;4190.97904510477;4194.2869712149;4206.453161089054;4231.365295205728;4141.308149702687;4182.6862985276075;4182.769947652893;4198.265303571863;4225.492940847118;3694.8522059117604;3738.9252214955823;3747.831347589365;3753.6246375362516;3756.981215093934;5129.281690633642;5231.529162375405;5235.685858848481;5269.789208431696;5310.601704872111;4796.184648609745;4804.159272127364;4829.492903245179;4835.395575553056;4853.684510506806;5430.891382172374;5639.379668236491;5659.236003139606;5660.575456840754;5696.971515142439;4051.696122790773;4054.1283624020975;4070.3690927905927;4072.450219220397;4082.12234369609;5874.441928016831;5892.381299963527;5896.97051514744;5903.966805809009;5931.911021334683;4174.911844787278;4202.684798640114;4209.6000879916355;4215.915681686411;4226.513950895686;4153.522344930311;4206.768627725443;4213.452251207324;4233.470816147981;4256.382824490432;4813;4814;4826.758662066883;4830.903381932341;4852.344933434012;5706.71466426677;5723.624695751299;5726.455986681259;5734;5772.740226689809;1349.9730005399838;1401.978970315446;1409.4996276321963;1415.4550498058322;1423.0821120377298;944.5324564340696;960.7694153403133;972.9464879431557;977.731123940922;988.7478692933399;5260.52755205053;5300.92048619271;5306.9386122775595;5310.7610157543;5354.732263386815;5352.4915133062295;5552.583556233299;5622.7750889963945;5642.576806739511;5665.291838520185;5141.203113517411;5196.558292545101;5287.9471205287455;5301.231321458367;5308.4074777566575;2971.4057188562374;2973.390454956772;2981.4633365994328;2981.98819192617;3004.248937765559;5772.711364431846;5859.619124756877;5864.709763851945;5872.7357268923;5903.881922361528;5397.1110111460675;5706.42935706426;5712.714364281769;5733.942660573341;5736.110902809987;5830.5044071254415;5846.4153584642045;5853.2976042875125;5854.648721076749;5909.020478139785;5463.4263402342185;5583.0787919992745;5614.29821272341;5614.915776263441;5823.359430462561;5767.192593037062;5772.797952071657;5789.2763404574425;5790.7973220937065;5850.06947707247;6100;6134.171886795226;6143.201383820075;6159;6190.597611155261;3958.3072962230917;4053.7162398631813;4125.690573206962;4149.024979129842;4164.37534369847;5742.253507044057;5750.712464376848;5754.597178197486;5757.337906140848;5794.971025144847;5629.521490673339;5639.915401269066;5641.464060914206;5671.326958547321;5673.375928647928;5556.472135147154;5627.240322556402;5633.436656334334;5634.211210430461;5642.7460764266525;5640.71796410186;5664.291963504562;5670.489655930986;5685.71571421419;5693.459121383462;5494.615376923737;5676.120201368794;5690.94717477268;5692.516136128475;5694.743736531949;5497.47773961473;5529.751161197675;5534;5534.889302214014;5546.55627549787 18:43:09 INFO - PROCESS | 27845 | |5;yui.html;727.4834867244253;747.1967634792593;776.7553220735468;813.8697808350663;828.9253967142956;14635.780463293058;16009.359625614961;16013.439529616466;16046.43837465689;16048.438304659325;14798.556043318691;14952.177630230333;15030.098194108361;15032.624184395396;15073.773893391608;677.3903486861815;691.1429827014491;763.1300317637897;792.7819849541364;801.3188789528912;787.7046107709609;794.8052727081844;794.8767940969159;796.024869534821;796.1918652567648;864.9005364383082;873.2664561768114;874.8250349930007;879.2438502887512;879.9648014079429;862.4997501449176;863.1627321498169;868.8262347530456;874.2481465939255;875.9737207883774;637.0857819029707;637.6620391192675;638.5562034386104;639.223343637478;640.6412409050946;14857.777133343008;15367.462138825087;15369.769453458208;15652.98255613393;15729.292181851844;14983.850161498354;15252.923735381251;15254.923725381414;15682.607934801606;15698.293576789072;12009.219400738924;12131.757364852654;12159.696007599881;12322.81515777264;12326.568570100004;3676.914706161307;3677.7425580209397;3732.869349572779;3738.925221495555;3774.9622503774885;71096;71154.64422677906;71171.64414177896;71796;73175.26824731738;11138.66584002465;11201.383923884265;11259;11288.379139147426;11308.886911130949;20722.792772072087;20780.480487987617;20841;21131.8943405282;21210.893945530173;7702.845943081107;7846.136924938252;7920.287174154354;7947.705134965784;8052.879206811903;4233.851815186454;4262.680298977589;4319.913601727948;4333.349997500337;4359.498657654347;3451.3787518246454;3505.8422370993367;3533.94699079514;3558.181618227822;3574.0528759878653;600.2586805295457;601.7653115285042;602.728772052578;612.3662009819815;618.116093985605;702.3748863511479;726.280981827997;730.5689643110484;730.722325516311;741.8034220931457;9241.168294853496;9431.575579098957;9452.8582071269;9462.763430914145;9691.590460821224;736.4550232827776;749.9775006749697;750.2310132114626;755.8941748155263;759.8214419611387;4020.8190631421658;4293.162833247487;4351.19502891966;4352.238358287351;4360.324149756792;78372;83715;84116.57941710252;84316.57841710752;86777.56611216904;70740.29259707338;71031.28968710247;73022.63488682522;75503.24496755072;75594;64388.35611643917;65128.67435662791;65396.67301663556;65969.67015164894;66373.33626663768;8520.74437766868;8552.786180345542;8575.828483430172;8786.384953053224;8845.380823342304;43779.343309851014;44008.559914400445;44486;45205.547944520134;45478;56237;56646.716766415906;57005;57735;58545.70727146337;10628.096611788085;10790.190735694698;10820.945895270474;10826.89173108259;11001.119910406987;12497.875021250034;12731.427085780977;12895;12926.418311176209;13173;11391.658250252505;11479.713007174887;11611.767764644828;12606.432710528232;12936;13047.217166970013;13083.215007099605;13154.802677959648;13448.529301474402;13707;13109.60671179866;13281.335933203494;13390.93304533471;13488.258145802076;13581.32093395346;10429.634962776267;10872.836907446554;10884.78230435371;11122.388268645302;11322;10828.18788590843;11320.248713720985;11366.374849383032;11450.312981221154;11476.426178690865 18:43:09 INFO - PROCESS | 27845 | __end_tp_report 18:43:09 INFO - PROCESS | 27845 | __start_cc_report 18:43:09 INFO - PROCESS | 27845 | _x_x_mozilla_cycle_collect,309 18:43:09 INFO - PROCESS | 27845 | __end_cc_report 18:43:09 INFO - PROCESS | 27845 | __startTimestamp1482979389292__endTimestamp 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | ------- Summary: start ------- 18:43:09 INFO - PROCESS | 27845 | Number of tests: 6 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#0] dojo.html Cycles:165 Average:5419.13 Median:5647.27 stddev:631.00 (11.2%) stddev-sans-first:608.03 18:43:09 INFO - PROCESS | 27845 | Values: 3181.8 3208.4 3221.8 3243.1 3244.1 5358.6 5411.6 5445.0 5450.2 5467.7 5414.6 5415.9 5483.0 5507.6 5563.1 4902.1 4907.5 4956.4 5005.0 5041.3 4908.8 5035.5 5062.4 5080.5 5104.1 4867.8 4994.9 5037.9 5048.4 5064.9 4872.0 4886.4 4897.8 4913.1 5011.3 4866.3 4887.5 4890.9 4903.2 4917.9 5389.3 5404.6 5423.1 5433.6 5434.7 5626.1 5667.5 5792.2 5810.3 5828.2 5907.1 5907.4 5918.0 5918.6 5952.9 5470.4 5478.8 5479.1 5482.0 5487.0 5754.5 5804.7 5883.1 5932.9 5990.8 5716.5 5723.8 5744.0 5766.2 5777.9 5756.9 5782.8 5827.9 5844.2 5864.6 5624.5 5663.9 5793.3 5820.4 5837.7 5852.3 5872.7 5885.8 5897.7 5939.1 4366.1 4372.9 4374.5 4377.1 4408.8 3805.1 3822.9 3841.6 3894.2 3945.4 5578.9 5619.8 5651.7 5659.7 5660.0 5834.4 5881.5 5889.9 5891.8 5904.4 5368.1 5510.6 5573.9 5623.0 5623.9 4797.7 4843.6 4853.7 4879.5 4923.9 6129.7 6155.6 6163.0 6169.1 6178.8 5674.4 5876.9 5879.2 5882.1 5905.0 5916.6 6061.4 6070.6 6088.8 6164.7 5748.6 5788.0 5793.0 5804.7 5826.9 5851.0 5867.9 5879.1 5879.3 5912.1 5585.8 5647.3 5651.5 5664.7 5703.1 5696.1 5698.6 5698.9 5733.5 5738.2 5565.4 5723.1 5731.2 5734.1 5735.9 5636.2 5691.4 5704.1 5712.1 5803.9 5533.3 5591.4 5620.0 5621.6 5641.5 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#1] ext.html Cycles:180 Average:53685.90 Median:190730.91 stddev:105631.28 (55.4%) stddev-sans-first:105852.25 18:43:09 INFO - PROCESS | 27845 | Values: 545.0 552.3 560.8 574.1 584.6 40041.8 40982.8 41118.6 44943.8 45106.5 42137.2 42476.6 42555.6 42617.4 42618.4 8932.8 9092.1 9113.6 9116.5 10092.6 10063.2 10411.9 10430.3 10449.6 10454.5 10496.2 10511.4 10558.0 10569.9 10571.3 10390.4 10433.2 10445.3 10449.3 10462.7 10116.8 10246.4 10262.6 10277.2 10327.4 36308.5 36764.0 36766.6 36907.0 36918.8 3993.1 4005.0 4061.3 4109.3 4144.8 28089.9 28378.9 28481.3 28773.3 28814.6 4231.9 4360.3 4399.5 4417.7 4422.2 325771.0 328364.0 328862.0 330564.0 331053.0 50473.0 50731.7 50733.7 50809.2 50881.7 54083.0 54140.7 54202.7 54260.0 54327.0 14103.3 14441.9 14446.4 14474.2 14501.4 9824.9 9876.4 10281.2 10773.1 10832.5 16081.7 16178.2 16181.9 16233.7 16298.7 6657.2 6658.6 6696.0 6753.8 6815.8 827.5 827.7 849.1 854.8 865.7 27101.3 27880.7 28409.0 29651.7 31220.1 1023.4 1032.9 1037.6 1055.5 1069.3 13882.1 14261.4 14372.7 14554.6 14814.8 495807.0 496506.0 498054.0 498058.0 498429.0 50853.2 51121.2 51332.5 51357.0 51539.5 1315.5 1334.9 1368.1 1372.6 1373.7 26667.3 26778.3 26929.0 27706.0 27796.3 43526.8 44191.0 44287.1 44426.0 44470.6 346787.0 347549.0 348486.0 348777.0 349725.0 24779.5 24961.5 25642.5 25738.7 27151.9 40185.6 40316.0 40438.8 41418.4 41686.6 34306.0 35109.5 35195.5 35286.6 35304.0 34243.3 34447.5 34563.3 34607.5 34674.8 34354.3 35064.1 35187.5 35261.1 35393.8 28844.7 31322.2 31337.0 31519.5 31697.8 24271.6 24275.2 24459.6 24483.3 25010.1 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#2] jquery.html Cycles:180 Average:25812.00 Median:204323.68 stddev:69492.81 (34.0%) stddev-sans-first:69661.56 18:43:09 INFO - PROCESS | 27845 | Values: 399.3 401.7 410.6 412.5 426.4 15017.7 17313.4 18303.9 18790.7 18833.8 18088.4 18615.3 18750.2 18848.6 19079.9 5113.1 5217.1 5577.0 5688.8 5720.4 5623.6 5645.4 5681.2 5698.6 5701.7 5642.4 5671.2 5886.0 5917.1 5970.6 5794.5 5888.4 5919.7 5932.6 5936.8 5754.5 5784.1 5799.7 5803.7 5806.6 13477.8 13565.9 20916.2 21841.5 21995.9 2831.6 3005.7 3024.5 3025.3 3035.9 9688.3 9967.3 9990.9 10055.7 10146.6 1882.2 1998.9 2105.8 2177.1 2185.2 74470.0 76146.6 78013.6 78236.0 81148.2 10308.2 10782.9 10801.8 10973.1 11215.0 12007.3 15270.5 15288.8 15351.5 15426.7 7304.7 7334.9 7429.9 7581.6 7583.4 7136.4 7238.5 7375.7 7395.2 7420.4 3927.7 4000.8 4060.4 4155.7 4158.1 1934.1 1970.6 2024.3 2028.9 2034.7 876.9 887.7 895.4 896.4 922.8 17320.4 17361.0 17487.0 17678.8 18448.4 898.6 908.5 921.1 923.6 940.2 9931.2 10238.4 10368.7 10427.1 10491.7 404587.0 421523.0 425923.0 426697.0 427457.0 74211.6 75663.6 78458.6 79624.2 80400.0 945.1 966.7 988.3 988.4 996.6 15667.7 15907.4 15978.4 16296.5 16386.9 37062.3 38211.8 38511.6 38557.6 38691.4 50251.0 50398.7 50852.7 52964.0 53543.0 7948.1 8073.8 8080.6 8223.5 8229.2 10209.3 11819.8 12073.2 12132.5 12316.7 10511.7 10562.6 10729.7 10730.2 10903.6 10454.7 10730.5 10783.9 10864.6 11023.4 10116.7 10146.6 10772.8 10791.9 11214.7 8132.3 8295.0 8341.2 8393.8 8432.8 10479.4 10772.9 10889.2 10918.1 11204.9 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#3] mootools.html Cycles:180 Average:5552.65 Median:5215.36 stddev:3077.15 (59.0%) stddev-sans-first:3069.29 18:43:09 INFO - PROCESS | 27845 | Values: 1314.1 1327.4 1341.5 1358.8 1362.5 7636.2 7916.4 8123.0 8552.6 8588.8 8092.4 8292.7 8347.3 8592.4 8597.1 2323.4 2371.7 2374.2 2375.0 2385.5 2291.0 2310.0 2354.4 2372.7 2388.5 2335.5 2363.1 2364.8 2366.5 2369.6 2277.0 2279.6 2294.4 2301.6 2306.2 2265.5 2278.1 2283.3 2294.6 2350.8 8258.5 8330.6 8551.1 8677.8 8682.7 5178.9 5252.3 5256.9 5261.3 5274.8 3149.2 3291.9 3336.7 3343.3 3351.3 1411.1 1467.8 1474.3 1510.6 1573.6 11707.2 11777.9 11850.8 11861.4 11888.6 4443.6 4488.6 4542.3 4562.3 4633.7 9168.4 9172.0 9276.5 9301.3 9315.1 5132.6 5251.8 5268.7 5271.8 5309.1 4760.9 4807.0 4859.5 4908.4 4917.0 3593.9 3600.8 3681.8 3712.3 3795.7 1100.9 1127.1 1132.2 1176.0 1217.0 939.3 950.5 958.5 960.5 969.3 5308.1 5543.6 5563.0 5815.8 5823.1 909.9 918.5 922.7 923.0 924.5 3715.2 3738.1 3801.6 3810.4 3874.9 9993.4 10236.6 10296.4 10322.6 10323.6 10088.7 10188.2 10202.9 10223.5 10276.0 7552.4 7676.3 7809.8 7830.1 7964.7 5708.0 5852.4 5885.2 5956.1 6106.0 9445.3 9514.9 9535.3 9663.0 9668.8 9479.3 9543.8 9610.9 9647.4 9663.8 7180.8 7347.6 7431.5 7519.1 7646.1 8198.6 8230.8 8305.2 8307.0 8324.9 7425.1 7457.3 7491.7 7623.5 7679.7 6984.4 7382.2 7401.2 7414.7 7466.5 6253.3 6435.4 6452.5 6484.2 6507.1 4822.3 4833.2 4919.4 4952.8 5176.5 4760.7 4852.8 4855.8 4864.5 4883.9 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#4] prototype.html Cycles:180 Average:4749.61 Median:5399.31 stddev:1383.96 (25.6%) stddev-sans-first:1348.51 18:43:09 INFO - PROCESS | 27845 | Values: 384.8 396.1 397.7 399.4 404.7 5237.4 5398.6 5481.8 5552.3 5605.9 5323.2 5389.0 5400.0 5417.9 5445.6 4318.2 4320.1 4321.3 4322.2 4368.9 4122.9 4250.3 4355.5 4356.4 4357.7 4190.0 4191.0 4194.3 4206.5 4231.4 4141.3 4182.7 4182.8 4198.3 4225.5 3694.9 3738.9 3747.8 3753.6 3757.0 5129.3 5231.5 5235.7 5269.8 5310.6 4796.2 4804.2 4829.5 4835.4 4853.7 5430.9 5639.4 5659.2 5660.6 5697.0 4051.7 4054.1 4070.4 4072.5 4082.1 5874.4 5892.4 5897.0 5904.0 5931.9 4174.9 4202.7 4209.6 4215.9 4226.5 4153.5 4206.8 4213.5 4233.5 4256.4 4813.0 4814.0 4826.8 4830.9 4852.3 5706.7 5723.6 5726.5 5734.0 5772.7 1350.0 1402.0 1409.5 1415.5 1423.1 944.5 960.8 972.9 977.7 988.7 5260.5 5300.9 5306.9 5310.8 5354.7 5352.5 5552.6 5622.8 5642.6 5665.3 5141.2 5196.6 5287.9 5301.2 5308.4 2971.4 2973.4 2981.5 2982.0 3004.2 5772.7 5859.6 5864.7 5872.7 5903.9 5397.1 5706.4 5712.7 5733.9 5736.1 5830.5 5846.4 5853.3 5854.6 5909.0 5463.4 5583.1 5614.3 5614.9 5823.4 5767.2 5772.8 5789.3 5790.8 5850.1 6100.0 6134.2 6143.2 6159.0 6190.6 3958.3 4053.7 4125.7 4149.0 4164.4 5742.3 5750.7 5754.6 5757.3 5795.0 5629.5 5639.9 5641.5 5671.3 5673.4 5556.5 5627.2 5633.4 5634.2 5642.7 5640.7 5664.3 5670.5 5685.7 5693.5 5494.6 5676.1 5690.9 5692.5 5694.7 5497.5 5529.8 5534.0 5534.9 5546.6 18:43:09 INFO - PROCESS | 27845 | 18:43:09 INFO - PROCESS | 27845 | [#5] yui.html Cycles:180 Average:17627.97 Median:43893.95 stddev:22926.45 (52.2%) stddev-sans-first:22955.64 18:43:09 INFO - PROCESS | 27845 | Values: 727.5 747.2 776.8 813.9 828.9 14635.8 16009.4 16013.4 16046.4 16048.4 14798.6 14952.2 15030.1 15032.6 15073.8 677.4 691.1 763.1 792.8 801.3 787.7 794.8 794.9 796.0 796.2 864.9 873.3 874.8 879.2 880.0 862.5 863.2 868.8 874.2 876.0 637.1 637.7 638.6 639.2 640.6 14857.8 15367.5 15369.8 15653.0 15729.3 14983.9 15252.9 15254.9 15682.6 15698.3 12009.2 12131.8 12159.7 12322.8 12326.6 3676.9 3677.7 3732.9 3738.9 3775.0 71096.0 71154.6 71171.6 71796.0 73175.3 11138.7 11201.4 11259.0 11288.4 11308.9 20722.8 20780.5 20841.0 21131.9 21210.9 7702.8 7846.1 7920.3 7947.7 8052.9 4233.9 4262.7 4319.9 4333.3 4359.5 3451.4 3505.8 3533.9 3558.2 3574.1 600.3 601.8 602.7 612.4 618.1 702.4 726.3 730.6 730.7 741.8 9241.2 9431.6 9452.9 9462.8 9691.6 736.5 750.0 750.2 755.9 759.8 4020.8 4293.2 4351.2 4352.2 4360.3 78372.0 83715.0 84116.6 84316.6 86777.6 70740.3 71031.3 73022.6 75503.2 75594.0 64388.4 65128.7 65396.7 65969.7 66373.3 8520.7 8552.8 8575.8 8786.4 8845.4 43779.3 44008.6 44486.0 45205.5 45478.0 56237.0 56646.7 57005.0 57735.0 58545.7 10628.1 10790.2 10820.9 10826.9 11001.1 12497.9 12731.4 12895.0 12926.4 13173.0 11391.7 11479.7 11611.8 12606.4 12936.0 13047.2 13083.2 13154.8 13448.5 13707.0 13109.6 13281.3 13390.9 13488.3 13581.3 10429.6 10872.8 10884.8 11122.4 11322.0 10828.2 11320.2 11366.4 11450.3 11476.4 18:43:09 INFO - PROCESS | 27845 | -------- Summary: end -------- 18:43:09 INFO - PROCESS | 27845 | 18:43:10 INFO - TEST-INFO | 27845: exit 0 18:43:10 INFO - TEST-OK | dromaeo_css | took 1114868ms 18:43:10 INFO - TEST-START | kraken 18:43:10 INFO - operating with platform_type : linux_ 18:43:10 INFO - Initialising browser for kraken test... 18:43:10 INFO - TEST-INFO | started process 28197 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjip38B/profile http://localhost:40865/getInfo.html) 18:43:10 INFO - PROCESS | 28197 | 1482979390557 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 18:43:14 INFO - PROCESS | 28197 | __metrics Screen width/height:1600/1200 18:43:14 INFO - PROCESS | 28197 | colorDepth:24 18:43:14 INFO - PROCESS | 28197 | Browser inner width/height: 1024/697 18:43:14 INFO - PROCESS | 28197 | __metrics 18:43:15 ERROR - PROCESS | 28197 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 18:43:16 INFO - TEST-INFO | 28197: exit 0 18:43:16 INFO - Browser initialized. 18:43:16 INFO - Running cycle 1/1 for kraken test... 18:43:16 INFO - TEST-INFO | started process 28318 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjip38B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:28 INFO - PROCESS | 28318 | RSS: Main: 154214400 18:43:28 INFO - PROCESS | 28318 | 18:43:31 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40865/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:31 INFO - PROCESS | 28318 | RSS: Main: 161337344 18:43:31 INFO - PROCESS | 28318 | 18:43:34 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40865/tests/kraken/driver.html?testName=audio-dft) 18:43:35 INFO - PROCESS | 28318 | RSS: Main: 159612928 18:43:35 INFO - PROCESS | 28318 | 18:43:38 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40865/tests/kraken/driver.html?testName=audio-fft) 18:43:38 INFO - PROCESS | 28318 | RSS: Main: 155828224 18:43:38 INFO - PROCESS | 28318 | 18:43:40 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40865/tests/kraken/driver.html?testName=audio-oscillator) 18:43:41 INFO - PROCESS | 28318 | RSS: Main: 156737536 18:43:41 INFO - PROCESS | 28318 | 18:43:43 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40865/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:43:44 INFO - PROCESS | 28318 | RSS: Main: 156745728 18:43:44 INFO - PROCESS | 28318 | 18:43:47 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40865/tests/kraken/driver.html?testName=imaging-darkroom) 18:43:47 INFO - PROCESS | 28318 | RSS: Main: 161464320 18:43:47 INFO - PROCESS | 28318 | 18:43:51 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40865/tests/kraken/driver.html?testName=imaging-desaturate) 18:43:51 INFO - PROCESS | 28318 | RSS: Main: 159088640 18:43:51 INFO - PROCESS | 28318 | 18:43:54 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40865/tests/kraken/driver.html?testName=json-parse-financial) 18:43:55 INFO - PROCESS | 28318 | RSS: Main: 159731712 18:43:55 INFO - PROCESS | 28318 | 18:43:57 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40865/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:43:57 INFO - PROCESS | 28318 | RSS: Main: 159518720 18:43:57 INFO - PROCESS | 28318 | 18:43:59 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:00 INFO - PROCESS | 28318 | RSS: Main: 159838208 18:44:00 INFO - PROCESS | 28318 | 18:44:02 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:02 INFO - PROCESS | 28318 | RSS: Main: 160206848 18:44:02 INFO - PROCESS | 28318 | 18:44:05 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:06 INFO - PROCESS | 28318 | RSS: Main: 160301056 18:44:06 INFO - PROCESS | 28318 | 18:44:09 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:09 INFO - PROCESS | 28318 | RSS: Main: 160550912 18:44:09 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | Cycle 1(1): loaded http://localhost:40865/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40865/tests/kraken/driver.html?testName=ai-astar) 18:44:11 INFO - PROCESS | 28318 | RSS: Main: 162279424 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | __start_tp_report 18:44:11 INFO - PROCESS | 28318 | _x_x_mozilla_page_load 18:44:11 INFO - PROCESS | 28318 | _x_x_mozilla_page_load_details 18:44:11 INFO - PROCESS | 28318 | |i|pagename|runs| 18:44:11 INFO - PROCESS | 28318 | |0;ai-astar;104;115;115;112;114;112;114;113;112;115 18:44:11 INFO - PROCESS | 28318 | |1;audio-beat-detection;154;122;123;162;129;127;122;123;140;122 18:44:11 INFO - PROCESS | 28318 | |2;audio-dft;163;168;161;163;164;164;177;163;161;163 18:44:11 INFO - PROCESS | 28318 | |3;audio-fft;76;76;76;109;78;76;77;77;92;76 18:44:11 INFO - PROCESS | 28318 | |4;audio-oscillator;83;83;102;84;84;84;97;84;83;110 18:44:11 INFO - PROCESS | 28318 | |5;imaging-gaussian-blur;118;118;117;117;117;118;118;118;118;121 18:44:11 INFO - PROCESS | 28318 | |6;imaging-darkroom;149;148;147;148;149;149;148;147;149;148 18:44:11 INFO - PROCESS | 28318 | |7;imaging-desaturate;98;100;96;96;96;96;96;98;96;96 18:44:11 INFO - PROCESS | 28318 | |8;json-parse-financial;59;59;56;56;57;59;56;57;57;59 18:44:11 INFO - PROCESS | 28318 | |9;json-stringify-tinderbox;68;69;70;69;68;69;68;69;69;70 18:44:11 INFO - PROCESS | 28318 | |10;stanford-crypto-aes;83;82;90;84;83;82;81;87;81;82 18:44:11 INFO - PROCESS | 28318 | |11;stanford-crypto-ccm;135;141;132;128;126;138;141;130;180;144 18:44:11 INFO - PROCESS | 28318 | |12;stanford-crypto-pbkdf2;177;173;172;169;168;171;170;174;171;166 18:44:11 INFO - PROCESS | 28318 | |13;stanford-crypto-sha256-iterative;66;64;66;77;63;69;64;64;62;64 18:44:11 INFO - PROCESS | 28318 | __end_tp_report 18:44:11 INFO - PROCESS | 28318 | __start_cc_report 18:44:11 INFO - PROCESS | 28318 | _x_x_mozilla_cycle_collect,742 18:44:11 INFO - PROCESS | 28318 | __end_cc_report 18:44:11 INFO - PROCESS | 28318 | __startTimestamp1482979451793__endTimestamp 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | ------- Summary: start ------- 18:44:11 INFO - PROCESS | 28318 | Number of tests: 14 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.27 (2.9%) stddev-sans-first:1.33 18:44:11 INFO - PROCESS | 28318 | Values: 104.0 115.0 115.0 112.0 114.0 112.0 114.0 113.0 112.0 115.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:128.00 stddev:14.69 (11.5%) stddev-sans-first:13.34 18:44:11 INFO - PROCESS | 28318 | Values: 154.0 122.0 123.0 162.0 129.0 127.0 122.0 123.0 140.0 122.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#2] audio-dft Cycles:10 Average:164.70 Median:163.50 stddev:4.74 (2.9%) stddev-sans-first:4.99 18:44:11 INFO - PROCESS | 28318 | Values: 163.0 168.0 161.0 163.0 164.0 164.0 177.0 163.0 161.0 163.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.50 stddev:10.90 (14.3%) stddev-sans-first:11.40 18:44:11 INFO - PROCESS | 28318 | Values: 76.0 76.0 76.0 109.0 78.0 76.0 77.0 77.0 92.0 76.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:84.00 stddev:9.89 (11.8%) stddev-sans-first:10.22 18:44:11 INFO - PROCESS | 28318 | Values: 83.0 83.0 102.0 84.0 84.0 84.0 97.0 84.0 83.0 110.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:1.15 (1.0%) stddev-sans-first:1.22 18:44:11 INFO - PROCESS | 28318 | Values: 118.0 118.0 117.0 117.0 117.0 118.0 118.0 118.0 118.0 121.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.50 stddev:0.79 (0.5%) stddev-sans-first:0.78 18:44:11 INFO - PROCESS | 28318 | Values: 149.0 148.0 147.0 148.0 149.0 149.0 148.0 147.0 149.0 148.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:96.00 stddev:1.40 (1.5%) stddev-sans-first:1.41 18:44:11 INFO - PROCESS | 28318 | Values: 98.0 100.0 96.0 96.0 96.0 96.0 96.0 98.0 96.0 96.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.35 (2.3%) stddev-sans-first:1.32 18:44:11 INFO - PROCESS | 28318 | Values: 59.0 59.0 56.0 56.0 57.0 59.0 56.0 57.0 57.0 59.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 18:44:11 INFO - PROCESS | 28318 | Values: 68.0 69.0 70.0 69.0 68.0 69.0 68.0 69.0 69.0 70.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.00 stddev:2.88 (3.5%) stddev-sans-first:3.05 18:44:11 INFO - PROCESS | 28318 | Values: 83.0 82.0 90.0 84.0 83.0 82.0 81.0 87.0 81.0 82.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:139.50 stddev:15.45 (11.1%) stddev-sans-first:16.30 18:44:11 INFO - PROCESS | 28318 | Values: 135.0 141.0 132.0 128.0 126.0 138.0 141.0 130.0 180.0 144.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.50 stddev:3.14 (1.8%) stddev-sans-first:2.51 18:44:11 INFO - PROCESS | 28318 | Values: 177.0 173.0 172.0 169.0 168.0 171.0 170.0 174.0 171.0 166.0 18:44:11 INFO - PROCESS | 28318 | 18:44:11 INFO - PROCESS | 28318 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.00 stddev:4.36 (6.7%) stddev-sans-first:4.62 18:44:11 INFO - PROCESS | 28318 | Values: 66.0 64.0 66.0 77.0 63.0 69.0 64.0 64.0 62.0 64.0 18:44:11 INFO - PROCESS | 28318 | -------- Summary: end -------- 18:44:11 INFO - PROCESS | 28318 | 18:44:12 INFO - TEST-INFO | 28318: exit 0 18:44:12 INFO - TEST-OK | kraken | took 62699ms 18:44:12 INFO - SUITE-END | took 1177s 18:44:12 INFO - Completed test suite (00:19:37) 18:44:12 INFO - javascript benchmark 18:44:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7035.616673331845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3181.809091454513, 3208.4385232584305, 3221.774475786696, 3243.1243564237657, 3244.1403028197547, 5358.571314294857, 5411.621186516945, 5444.972775136123, 5450.1552259399805, 5467.699276539789, 5414.620976531644, 5415.918761218575, 5482.972585137079, 5507.586930980173, 5563.13771365438, 4902.117618828618, 4907.484714105021, 4956.355673762406, 5004.999000199975, 5041.294218809369, 4908.779104940286, 5035.471275516073, 5062.392512898457, 5080.542751152396, 5104.132297509414, 4867.805287788484, 4994.900101997976, 5037.949620503785, 5048.419431765321, 5064.924026139611, 4871.951280487221, 4886.364772579577, 4897.828575999859, 4913.091078150518, 5011.3485246918035, 4866.27449809391, 4887.535684110004, 4890.877728056791, 4903.166461701484, 4917.901641967141, 5389.326334208224, 5404.5676345892625, 5423.078076726966, 5433.646812957185, 5434.673919564839, 5626.071698169797, 5667.489925906647, 5792.247007888989, 5810.33181184165, 5828.184054232411, 5907.113932910055, 5907.350191478934, 5918.0, 5918.556108291896, 5952.940470595282, 5470.370907345667, 5478.808241711561, 5479.0959491683425, 5481.972590137024, 5486.972565137149, 5754.482096611241, 5804.709764511757, 5883.117532370179, 5932.911006334908, 5990.820275391745, 5716.485516303553, 5723.828285151452, 5743.971280143573, 5766.192733017381, 5777.942220577825, 5756.913646295309, 5782.797602083907, 5827.854303642358, 5844.15259787337, 5864.589478736448, 5624.493795558419, 5663.858403539944, 5793.275840519935, 5820.35976042635, 5837.708114594253, 5852.326982397079, 5872.706364681749, 5885.793997210077, 5897.734601942837, 5939.07944268639, 4366.126774645084, 4372.934405983913, 4374.518802931675, 4377.146456440963, 4408.7575183365225, 3805.1058001369656, 3822.9426558601085, 3841.635044670752, 3894.240623078529, 3945.3687410014836, 5578.86052848682, 5619.80330688424, 5651.689157096399, 5659.745311460994, 5660.0, 5834.387389324144, 5881.529477641779, 5889.852753681106, 5891.764329426775, 5904.380040095728, 5368.114261146867, 5510.559155267569, 5573.860653483695, 5623.0, 5623.887522249532, 4797.688150270221, 4843.564079232816, 4853.684510506806, 4879.512048795092, 4923.90152196954, 6129.693515324305, 6155.569110162335, 6162.969185154046, 6169.074638804215, 6178.752849885954, 5674.432556744293, 5876.941230587639, 5879.176915231957, 5882.1470886722045, 5904.9704751475965, 5916.585838991229, 6061.36355682647, 6070.575059745952, 6088.786892458743, 6164.691765411621, 5748.626339287933, 5788.0, 5792.971035144966, 5804.6807425592, 5826.854328641818, 5851.0053290939795, 5867.882642347044, 5879.147523608967, 5879.2944846616965, 5912.054071348563, 5585.776568937359, 5647.265855438766, 5651.547876169979, 5664.716764161858, 5703.116017017369, 5696.145578163143, 5698.629589076697, 5698.886022279448, 5733.483986441241, 5738.196652468574, 5565.387807341108, 5723.055695810312, 5731.197632331394, 5734.139879018181, 5735.913961290667, 5636.154576813511, 5691.430856914276, 5704.11586204139, 5712.08606622955, 5803.912941305968, 5533.3083364579425, 5591.412901645269, 5619.971900140473, 5621.606487545832, 5641.520470759867], "value": 5375.868236033431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.0108053882203, 552.2710022769941, 560.7840981222231, 574.107263205715, 584.6229182177495, 40041.79979100104, 40982.795086024526, 41118.588814111885, 44943.77528112355, 45106.54893451065, 42137.15725685484, 42476.57523424773, 42555.57444425555, 42617.360739588934, 42618.360724589154, 8932.776680582989, 9092.090790920922, 9113.635454581809, 9116.498592577405, 10092.646757363495, 10063.194944404466, 10411.895881041168, 10430.269881108325, 10449.58201671936, 10454.52954617044, 10496.212784041189, 10511.421871797049, 10558.0, 10569.894301056967, 10571.260011799177, 10390.428526431044, 10433.16534677224, 10445.26883118182, 10449.26855120142, 10462.738431539272, 10116.797664046679, 10246.385216886936, 10262.640807571774, 10277.229207809369, 10327.431991240479, 36308.455373169425, 36764.0, 36766.632333676585, 36907.0, 36918.815405923066, 3993.1483247853707, 4004.979975100106, 4061.3095773718537, 4109.2808758467145, 4144.79276036197, 28089.85955070232, 28378.858105709547, 28481.28796780097, 28773.280667983257, 28814.567781483296, 4231.936520952188, 4360.345948107809, 4399.5160532341415, 4417.712848664826, 4422.204003279408, 325771.0, 328364.0, 328862.0, 330563.9999999976, 331053.0, 50473.0, 50731.74634126806, 50733.74633126811, 50809.237861432106, 50881.74559127255, 54083.0, 54140.72929635327, 54202.72898635561, 54260.0, 54327.0, 14103.2948352584, 14441.85558144426, 14446.422143114369, 14474.203918784568, 14501.419943202154, 9824.852627210597, 9876.35803672759, 10281.177505799518, 10773.138148948065, 10832.512536935857, 16081.678366432607, 16178.19109044543, 16181.919090404708, 16233.727278181064, 16298.67402651964, 6657.23441804189, 6658.600483970978, 6695.966520167465, 6753.797386078426, 6815.829604259834, 827.4662842466696, 827.664795757719, 849.0872312264217, 854.8418542569644, 865.6970060478782, 27101.322466938484, 27880.72119278822, 28409.0, 29651.703482964895, 31220.102270853153, 1023.4166525080782, 1032.850236715672, 1037.6368271105052, 1055.5408397347178, 1069.25152393325, 13882.08476219985, 14261.42954281817, 14372.712545749235, 14554.636134096521, 14814.777778333333, 495807.0, 496506.0, 498054.0, 498058.0, 498429.0, 50853.23720144201, 51121.233181502306, 51332.48667513352, 51357.0, 51539.48460515422, 1315.5001099582098, 1334.8998825088158, 1368.056041331498, 1372.5813626843708, 1373.6565858535366, 26667.33331666724, 26778.330541736614, 26929.0, 27706.0, 27796.30175813913, 43526.782366087966, 44191.0, 44287.11425771402, 44426.0, 44470.55529444664, 346787.0, 347549.0, 348486.0, 348777.0, 349725.0, 24779.50440991206, 24961.50538924588, 25642.487150257264, 25738.742612573635, 27151.86424067867, 40185.598144018186, 40316.0, 40438.79780601078, 41418.40851485011, 41686.58313416827, 34306.0, 35109.47335789914, 35195.472067919516, 35286.647133528335, 35304.0, 34243.31513369669, 34447.4832877512, 34563.30873382468, 34607.4808877862, 34674.82662586772, 34354.312913742084, 35064.12339691426, 35187.472187917716, 35261.11847203738, 35393.82303088468, 28844.711552884204, 31322.21694457657, 31337.0, 31519.52720709237, 31697.8415107923, 24271.635925461487, 24275.150369736974, 24459.633105503075, 24483.265502034967, 25010.124645637316], "value": 18627.804969190547, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.2573812708364, 401.7328476563086, 410.5607000509455, 412.54619918090106, 426.3562021347766, 15017.6996460071, 17313.394031208896, 18303.90848045758, 18790.718139227887, 18833.811661883377, 18088.36690715825, 18615.348462803806, 18750.156242969064, 18848.62302753944, 19079.904600477013, 5113.079645663779, 5217.113090774577, 5576.972115139419, 5688.829335119953, 5720.427957204267, 5623.578231632623, 5645.435456454363, 5681.176229446728, 5698.572607054467, 5701.6864072476, 5642.379338272786, 5671.2060311556415, 5885.970570147165, 5917.142014407908, 5970.552208584373, 5794.5074668653215, 5888.381719919411, 5919.704014799243, 5932.644041357532, 5936.762529498815, 5754.510866576346, 5784.13238014297, 5799.681017544033, 5803.7098145093, 5806.622569532967, 13477.797833032513, 13565.864341356559, 20916.163353465843, 21841.453963650878, 21995.890020549956, 2831.6177316062276, 3005.7144571265694, 3024.4555979923607, 3025.2588115911744, 3035.893743718981, 9688.267108388056, 9967.252456065824, 9990.850137247948, 10055.748606284827, 10146.644867429679, 1882.1530311359943, 1998.8700734452214, 2105.799949004842, 2177.096504950444, 2185.158713895146, 74470.0, 76146.6192669033, 78013.60993195111, 78236.0, 81148.18851811525, 10308.175345972304, 10782.946085269523, 10801.83797243042, 10973.067289280501, 11215.0, 12007.279563226235, 15270.465533706489, 15288.847111528965, 15351.53945381618, 15426.691466170614, 7304.671289791972, 7334.9266507335315, 7429.85140297191, 7581.583012934341, 7583.431242656823, 7136.429085673134, 7238.457115716343, 7375.741849035258, 7395.223501532261, 7420.369268612229, 3927.7446965947124, 4000.8399664013696, 4060.3503439449546, 4155.688323375759, 4158.08522125132, 1934.139308007951, 1970.6255811395788, 2024.2611446821804, 2028.8782673039666, 2034.7253120828796, 876.9298456123495, 887.671561522241, 895.4403497813867, 896.3591032411726, 922.7831459606987, 17320.39378621742, 17361.0, 17487.0, 17678.823211767976, 18448.446546603354, 898.6046139698511, 908.52756566585, 921.0559176843789, 923.5797712041006, 940.2008292951074, 9931.205503559842, 10238.389210751211, 10368.740781480372, 10427.11369533583, 10491.68524944253, 404587.0, 421523.0, 425923.0, 426697.0, 427457.0, 74211.62894185494, 75663.62168189124, 78458.60770696224, 79624.20375796284, 80400.0, 945.0738276488942, 966.6713317472094, 988.3328753091635, 988.4316518002178, 996.5814357969671, 15667.686646266782, 15907.363705451653, 15978.360865565248, 16296.511104666879, 16386.918065409598, 37062.25875482529, 38211.80894095623, 38511.61488385192, 38557.614423855404, 38691.41962870616, 50251.0, 50398.74800625973, 50852.745736271085, 52964.0, 53543.0, 7948.125706172432, 8073.7577872663915, 8080.595970201584, 8223.465474744122, 8229.218224268685, 10209.336393134423, 11819.763604728028, 12073.154879158727, 12132.514699412277, 12316.692082697646, 10511.684649460529, 10562.577496900345, 10729.731756705833, 10730.195235357223, 10903.563857445613, 10454.68635940923, 10730.463476825971, 10783.892161078602, 10864.565417383215, 11023.393713345842, 10116.696499105039, 10146.644867429899, 10772.784544309226, 10791.946040269748, 11214.719632009264, 8132.3494120471405, 8294.958525207336, 8341.24928756415, 8393.83212335762, 8432.789180270296, 10479.423631700025, 10772.946135269274, 10889.183311251529, 10918.126549875831, 11204.887951120385], "value": 8749.318715676072, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.093275639809, 1327.4292054416603, 1341.510348722716, 1358.7622166120927, 1362.489066600025, 7636.160022397532, 7916.40626952978, 8123.0, 8552.615132319044, 8588.828223435536, 8092.433529652927, 8292.70975515857, 8347.332213422927, 8592.441491303076, 8597.1402859714, 2323.442373830277, 2371.6561098640686, 2374.169040835711, 2374.964375534368, 2385.451346190377, 2290.9885450572806, 2309.9653505197434, 2354.387859156623, 2372.6915500984846, 2388.474535602165, 2335.532893421314, 2363.054778088761, 2364.8226383021256, 2366.4675448024227, 2369.5853225685523, 2277.0322612889454, 2279.5782780185637, 2294.380517260339, 2301.6317389217643, 2306.215886598556, 2265.4676151104477, 2278.100150440583, 2283.2807665585287, 2294.552562250362, 2350.82368822339, 8258.504489730576, 8330.583470826434, 8551.144885511461, 8677.783055423602, 8682.739517814476, 5178.870528236786, 5252.290940722992, 5256.8685782855355, 5261.316028916216, 5274.762635681365, 3149.165471150147, 3291.8683252669866, 3336.6830151135605, 3343.297907439416, 3351.2962277921665, 1411.1110000699493, 1467.78717086023, 1474.329180222996, 1510.644998425369, 1573.6065983504125, 11707.180497365187, 11777.94111029448, 11850.822237666442, 11861.406929653484, 11888.643340699706, 4443.555644435531, 4488.573585509372, 4542.273236282179, 4562.338460923148, 4633.745144017044, 9168.387009702203, 9171.954140229256, 9276.536173191313, 9301.255899528021, 9315.115064068903, 5132.589392848563, 5251.842444726664, 5268.710220937885, 5271.762770675329, 5309.070912590359, 4760.857174284777, 4807.038592281558, 4859.465458799529, 4908.361912951294, 4916.950830491721, 3593.9101522462147, 3600.801955892451, 3681.815909204529, 3712.3132220539287, 3795.7153213508545, 1100.9174311926638, 1127.0983213429224, 1132.2017977325966, 1175.9941200293945, 1216.9817452738216, 939.3189937295406, 950.5199874063553, 958.4680502321279, 960.4957397366298, 969.3263182088381, 5308.097623404031, 5543.61194716366, 5562.972185139049, 5815.767369305265, 5823.097419899837, 909.8908131024187, 918.4672889723944, 922.6632279218171, 922.9630814767335, 924.542351535994, 3715.20123173519, 3738.12154143776, 3801.581825999138, 3810.3522401191863, 3874.8837534873437, 9993.350432221881, 10236.590536378462, 10296.433696146783, 10322.638707645196, 10323.63867264642, 10088.69733907984, 10188.184945204366, 10202.897971020344, 10223.488825558841, 10276.0, 7552.358049565739, 7676.270754278335, 7809.80475488106, 7830.060392752906, 7964.681412743541, 5707.971460142673, 5852.356240813422, 5885.234919460443, 5956.076808094752, 6105.9694701526205, 9445.260874346637, 9514.904850951401, 9535.33252672334, 9663.0, 9668.75828104303, 9479.336446448959, 9543.809123817624, 9610.855837162308, 9647.421154730739, 9663.806723865624, 7180.820479487846, 7347.55914645123, 7431.479796414199, 7519.135299440634, 7646.120696119795, 8198.631061602124, 8230.753077407688, 8305.211004954519, 8307.0, 8324.875126872981, 7425.146108197627, 7457.291557302047, 7491.662875170521, 7623.542587444771, 7679.692812287446, 6984.371406573433, 7382.224866388952, 7401.185869554237, 7414.666340014604, 7466.477346585904, 6253.280872699515, 6435.420812126931, 6452.483801295979, 6484.157059582224, 6507.12153859223, 4822.324874517641, 4833.202521583899, 4919.385076865392, 4952.826651067196, 5176.50823171798, 4760.714357138583, 4852.781624826821, 4855.8057677692495, 4864.513548645107, 4883.876708357169], "value": 4495.21817058106, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.8325978199483, 396.1206122408254, 397.7275566237127, 399.3769719237987, 404.7369210013493, 5237.397699264586, 5398.64908780929, 5481.808136715215, 5552.333719953601, 5605.934872374246, 5323.1749078892735, 5389.0, 5399.9730001349935, 5417.945820541793, 5445.646033007852, 4318.244307246235, 4320.135972805436, 4321.330193819963, 4322.2003929273105, 4368.89077773055, 4122.9381559276635, 4250.319948808191, 4355.54089380058, 4356.411884395614, 4357.716748411359, 4189.97905010476, 4190.97904510477, 4194.2869712149, 4206.453161089054, 4231.365295205728, 4141.308149702687, 4182.6862985276075, 4182.769947652893, 4198.265303571863, 4225.492940847118, 3694.8522059117604, 3738.9252214955823, 3747.831347589365, 3753.6246375362516, 3756.981215093934, 5129.281690633642, 5231.529162375405, 5235.685858848481, 5269.789208431696, 5310.601704872111, 4796.184648609745, 4804.159272127364, 4829.492903245179, 4835.395575553056, 4853.684510506806, 5430.891382172374, 5639.379668236491, 5659.236003139606, 5660.575456840754, 5696.971515142439, 4051.696122790773, 4054.1283624020975, 4070.3690927905927, 4072.450219220397, 4082.12234369609, 5874.441928016831, 5892.381299963527, 5896.97051514744, 5903.966805809009, 5931.911021334683, 4174.911844787278, 4202.684798640114, 4209.6000879916355, 4215.915681686411, 4226.513950895686, 4153.522344930311, 4206.768627725443, 4213.452251207324, 4233.470816147981, 4256.382824490432, 4813.0, 4814.0, 4826.758662066883, 4830.903381932341, 4852.344933434012, 5706.71466426677, 5723.624695751299, 5726.455986681259, 5734.0, 5772.740226689809, 1349.9730005399838, 1401.978970315446, 1409.4996276321963, 1415.4550498058322, 1423.0821120377298, 944.5324564340696, 960.7694153403133, 972.9464879431557, 977.731123940922, 988.7478692933399, 5260.52755205053, 5300.92048619271, 5306.9386122775595, 5310.7610157543, 5354.732263386815, 5352.4915133062295, 5552.583556233299, 5622.7750889963945, 5642.576806739511, 5665.291838520185, 5141.203113517411, 5196.558292545101, 5287.9471205287455, 5301.231321458367, 5308.4074777566575, 2971.4057188562374, 2973.390454956772, 2981.4633365994328, 2981.98819192617, 3004.248937765559, 5772.711364431846, 5859.619124756877, 5864.709763851945, 5872.7357268923, 5903.881922361528, 5397.1110111460675, 5706.42935706426, 5712.714364281769, 5733.942660573341, 5736.110902809987, 5830.5044071254415, 5846.4153584642045, 5853.2976042875125, 5854.648721076749, 5909.020478139785, 5463.4263402342185, 5583.0787919992745, 5614.29821272341, 5614.915776263441, 5823.359430462561, 5767.192593037062, 5772.797952071657, 5789.2763404574425, 5790.7973220937065, 5850.06947707247, 6100.0, 6134.171886795226, 6143.201383820075, 6159.0, 6190.597611155261, 3958.3072962230917, 4053.7162398631813, 4125.690573206962, 4149.024979129842, 4164.37534369847, 5742.253507044057, 5750.712464376848, 5754.597178197486, 5757.337906140848, 5794.971025144847, 5629.521490673339, 5639.915401269066, 5641.464060914206, 5671.326958547321, 5673.375928647928, 5556.472135147154, 5627.240322556402, 5633.436656334334, 5634.211210430461, 5642.7460764266525, 5640.71796410186, 5664.291963504562, 5670.489655930986, 5685.71571421419, 5693.459121383462, 5494.615376923737, 5676.120201368794, 5690.94717477268, 5692.516136128475, 5694.743736531949, 5497.47773961473, 5529.751161197675, 5534.0, 5534.889302214014, 5546.55627549787], "value": 4322.453681991386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.4834867244253, 747.1967634792593, 776.7553220735468, 813.8697808350663, 828.9253967142956, 14635.780463293058, 16009.359625614961, 16013.439529616466, 16046.43837465689, 16048.438304659325, 14798.556043318691, 14952.177630230333, 15030.098194108361, 15032.624184395396, 15073.773893391608, 677.3903486861815, 691.1429827014491, 763.1300317637897, 792.7819849541364, 801.3188789528912, 787.7046107709609, 794.8052727081844, 794.8767940969159, 796.024869534821, 796.1918652567648, 864.9005364383082, 873.2664561768114, 874.8250349930007, 879.2438502887512, 879.9648014079429, 862.4997501449176, 863.1627321498169, 868.8262347530456, 874.2481465939255, 875.9737207883774, 637.0857819029707, 637.6620391192675, 638.5562034386104, 639.223343637478, 640.6412409050946, 14857.777133343008, 15367.462138825087, 15369.769453458208, 15652.98255613393, 15729.292181851844, 14983.850161498354, 15252.923735381251, 15254.923725381414, 15682.607934801606, 15698.293576789072, 12009.219400738924, 12131.757364852654, 12159.696007599881, 12322.81515777264, 12326.568570100004, 3676.914706161307, 3677.7425580209397, 3732.869349572779, 3738.925221495555, 3774.9622503774885, 71096.0, 71154.64422677906, 71171.64414177896, 71796.0, 73175.26824731738, 11138.66584002465, 11201.383923884265, 11259.0, 11288.379139147426, 11308.886911130949, 20722.792772072087, 20780.480487987617, 20841.0, 21131.8943405282, 21210.893945530173, 7702.845943081107, 7846.136924938252, 7920.287174154354, 7947.705134965784, 8052.879206811903, 4233.851815186454, 4262.680298977589, 4319.913601727948, 4333.349997500337, 4359.498657654347, 3451.3787518246454, 3505.8422370993367, 3533.94699079514, 3558.181618227822, 3574.0528759878653, 600.2586805295457, 601.7653115285042, 602.728772052578, 612.3662009819815, 618.116093985605, 702.3748863511479, 726.280981827997, 730.5689643110484, 730.722325516311, 741.8034220931457, 9241.168294853496, 9431.575579098957, 9452.8582071269, 9462.763430914145, 9691.590460821224, 736.4550232827776, 749.9775006749697, 750.2310132114626, 755.8941748155263, 759.8214419611387, 4020.8190631421658, 4293.162833247487, 4351.19502891966, 4352.238358287351, 4360.324149756792, 78372.0, 83715.0, 84116.57941710252, 84316.57841710752, 86777.56611216904, 70740.29259707338, 71031.28968710247, 73022.63488682522, 75503.24496755072, 75594.0, 64388.35611643917, 65128.67435662791, 65396.67301663556, 65969.67015164894, 66373.33626663768, 8520.74437766868, 8552.786180345542, 8575.828483430172, 8786.384953053224, 8845.380823342304, 43779.343309851014, 44008.559914400445, 44486.0, 45205.547944520134, 45478.0, 56237.0, 56646.716766415906, 57005.0, 57735.0, 58545.70727146337, 10628.096611788085, 10790.190735694698, 10820.945895270474, 10826.89173108259, 11001.119910406987, 12497.875021250034, 12731.427085780977, 12895.0, 12926.418311176209, 13173.0, 11391.658250252505, 11479.713007174887, 11611.767764644828, 12606.432710528232, 12936.0, 13047.217166970013, 13083.215007099605, 13154.802677959648, 13448.529301474402, 13707.0, 13109.60671179866, 13281.335933203494, 13390.93304533471, 13488.258145802076, 13581.32093395346, 10429.634962776267, 10872.836907446554, 10884.78230435371, 11122.388268645302, 11322.0, 10828.18788590843, 11320.248713720985, 11366.374849383032, 11450.312981221154, 11476.426178690865], "value": 7123.705370998571, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 112.0, 114.0, 112.0, 114.0, 113.0, 112.0, 115.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 123.0, 162.0, 129.0, 127.0, 122.0, 123.0, 140.0, 122.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 161.0, 163.0, 164.0, 164.0, 177.0, 163.0, 161.0, 163.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 109.0, 78.0, 76.0, 77.0, 77.0, 92.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 102.0, 84.0, 84.0, 84.0, 97.0, 84.0, 83.0, 110.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0, 121.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 147.0, 148.0, 149.0, 149.0, 148.0, 147.0, 149.0, 148.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 96.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 56.0, 56.0, 57.0, 59.0, 56.0, 57.0, 57.0, 59.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 69.0, 68.0, 69.0, 68.0, 69.0, 69.0, 70.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 90.0, 84.0, 83.0, 82.0, 81.0, 87.0, 81.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 141.0, 132.0, 128.0, 126.0, 138.0, 141.0, 130.0, 180.0, 144.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 173.0, 172.0, 169.0, 168.0, 171.0, 170.0, 174.0, 171.0, 166.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 77.0, 63.0, 69.0, 64.0, 64.0, 62.0, 64.0], "value": 65.9, "unit": "score"}]}]} 18:44:13 INFO - Return code: 0 18:44:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:13 ERROR - # TBPL SUCCESS # 18:44:13 INFO - Running post-action listener: _resource_record_post_action 18:44:13 INFO - [mozharness: 2016-12-29 02:44:13.117265Z] Finished run-tests step (success) 18:44:13 INFO - Running post-run listener: _resource_record_post_run 18:44:13 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 397205504; Read time: 8; Write time: 880520 18:44:13 INFO - TinderboxPrint: CPU usage
13.4% 18:44:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:44:13 INFO - TinderboxPrint: I/O write bytes / time
397,205,504 / 880,520 18:44:13 INFO - TinderboxPrint: CPU idle
8,251.6 (86.5%) 18:44:13 INFO - TinderboxPrint: CPU user
1,217.0 (12.8%) 18:44:13 INFO - TinderboxPrint: Swap in / out
0 / 0 18:44:13 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105459712; Read time: 0; Write time: 231148 18:44:13 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 291745792; Read time: 0; Write time: 649372 18:44:13 INFO - Running post-run listener: _upload_blobber_files 18:44:13 INFO - Blob upload gear active. 18:44:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:44:13 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:44:13 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:44:14 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:44:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:44:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:14 INFO - (blobuploader) - INFO - Done attempting. 18:44:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:18 INFO - (blobuploader) - INFO - Done attempting. 18:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:44:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:19 INFO - (blobuploader) - INFO - Done attempting. 18:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:20 INFO - (blobuploader) - INFO - Done attempting. 18:44:20 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:20 INFO - Return code: 0 18:44:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe3dfcd79e141eb844844f78a0c7c794049695ae38e4cfa8d7c2eaed2faf49918b26637993e875aab8c731bfdfe3bfaa4acf79ff8b835b3f579ee6115250fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c958106f1f8c3ba4b2ebe1be3b1f15fe2893c33a639a9d924baadda8943a99ff1f315596d351771b5e450b614ccf5459fbf7eae38cc08e5d88d7ff4397fb1722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec7a76ce44d474a0755efd277630577f0c82cfca54e52e7bc7681b325ac9b562b361b6f62339983747035612a380890ec9171ca368068ed5a1d9ff8bba31e340", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcd14d4e1abd5658fb2b3819a1d368505bbfd88bc38108444418d9337b6406adecd16a6998cec8497e8635c63af593687b8a47f7c89c0305baacc8ff80683e1a"} 18:44:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:20 INFO - Contents: 18:44:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe3dfcd79e141eb844844f78a0c7c794049695ae38e4cfa8d7c2eaed2faf49918b26637993e875aab8c731bfdfe3bfaa4acf79ff8b835b3f579ee6115250fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c958106f1f8c3ba4b2ebe1be3b1f15fe2893c33a639a9d924baadda8943a99ff1f315596d351771b5e450b614ccf5459fbf7eae38cc08e5d88d7ff4397fb1722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec7a76ce44d474a0755efd277630577f0c82cfca54e52e7bc7681b325ac9b562b361b6f62339983747035612a380890ec9171ca368068ed5a1d9ff8bba31e340", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcd14d4e1abd5658fb2b3819a1d368505bbfd88bc38108444418d9337b6406adecd16a6998cec8497e8635c63af593687b8a47f7c89c0305baacc8ff80683e1a"} 18:44:20 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:20 INFO - Copying logs to upload dir... 18:44:20 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.094149 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-12-28 18:44:20.275399) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 18:44:20.278951) ========= 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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 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/sha512/0fe3dfcd79e141eb844844f78a0c7c794049695ae38e4cfa8d7c2eaed2faf49918b26637993e875aab8c731bfdfe3bfaa4acf79ff8b835b3f579ee6115250fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c958106f1f8c3ba4b2ebe1be3b1f15fe2893c33a639a9d924baadda8943a99ff1f315596d351771b5e450b614ccf5459fbf7eae38cc08e5d88d7ff4397fb1722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec7a76ce44d474a0755efd277630577f0c82cfca54e52e7bc7681b325ac9b562b361b6f62339983747035612a380890ec9171ca368068ed5a1d9ff8bba31e340", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcd14d4e1abd5658fb2b3819a1d368505bbfd88bc38108444418d9337b6406adecd16a6998cec8497e8635c63af593687b8a47f7c89c0305baacc8ff80683e1a"} build_url:https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/AtdY6YuLRa-maW5unUc_GQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0fe3dfcd79e141eb844844f78a0c7c794049695ae38e4cfa8d7c2eaed2faf49918b26637993e875aab8c731bfdfe3bfaa4acf79ff8b835b3f579ee6115250fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c958106f1f8c3ba4b2ebe1be3b1f15fe2893c33a639a9d924baadda8943a99ff1f315596d351771b5e450b614ccf5459fbf7eae38cc08e5d88d7ff4397fb1722", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec7a76ce44d474a0755efd277630577f0c82cfca54e52e7bc7681b325ac9b562b361b6f62339983747035612a380890ec9171ca368068ed5a1d9ff8bba31e340", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcd14d4e1abd5658fb2b3819a1d368505bbfd88bc38108444418d9337b6406adecd16a6998cec8497e8635c63af593687b8a47f7c89c0305baacc8ff80683e1a"}' ========= master_lag: 0.23 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 18:44:20.516163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:44:20.519698) ========= 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=8783db0840cc980e80bb0bd400000236-1482977828.875940-1158232075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 18:44:20.539654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 18:44:20.540019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 18:44:20.540354) ========= ========= Total master_lag: 0.35 =========