builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1482767340.67 results: success (0) buildid: 20161226064736 builduid: 5cbeebde563446c784c3a4c58c2aa6d3 revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.674303) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.675187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.675509) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.691217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.691640) ========= 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=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-26 07:49:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.0M=0s 2016-12-26 07:49:02 (70.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107367 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.813182) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.813544) ========= 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=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014370 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.864908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:00.865501) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --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 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 --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=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-26 07:49:02,686 truncating revision to first 12 chars 2016-12-26 07:49:02,686 Setting DEBUG logging. 2016-12-26 07:49:02,686 attempt 1/10 2016-12-26 07:49:02,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1747e26ee37b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 07:49:03,326 unpacking tar archive at: mozilla-central-1747e26ee37b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.952329 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.832679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.832988) ========= script_repo_revision: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.833399) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.833682) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 07:49:01.844169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-26 07:49:01.844608) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:49:03 INFO - MultiFileLogger online at 20161226 07:49:03 in /builds/slave/test 07:49:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:49:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:03 INFO - {'append_to_log': False, 07:49:03 INFO - 'base_work_dir': '/builds/slave/test', 07:49:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:49:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:03 INFO - 'branch': 'Firefox-Non-PGO', 07:49:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:03 INFO - 'config_files': ('talos/linux_config.py',), 07:49:03 INFO - 'default_actions': ('clobber', 07:49:03 INFO - 'read-buildbot-config', 07:49:03 INFO - 'download-and-extract', 07:49:03 INFO - 'populate-webroot', 07:49:03 INFO - 'create-virtualenv', 07:49:03 INFO - 'install', 07:49:03 INFO - 'run-tests'), 07:49:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:03 INFO - 'download_minidump_stackwalk': True, 07:49:03 INFO - 'download_symbols': 'ondemand', 07:49:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:03 INFO - 'installer_path': 'installer.exe', 07:49:03 INFO - 'log_level': 'info', 07:49:03 INFO - 'log_name': 'talos', 07:49:03 INFO - 'log_to_console': True, 07:49:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:49:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:49:03 INFO - 'opt_config_files': (), 07:49:03 INFO - 'pip_index': False, 07:49:03 INFO - 'sps_profile': False, 07:49:03 INFO - 'sps_profile_interval': 0, 07:49:03 INFO - 'suite': 'dromaeojs', 07:49:03 INFO - 'system_bits': '32', 07:49:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:03 INFO - 'title': 'talos-linux64-ix-007', 07:49:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:03 INFO - 'use_talos_json': True, 07:49:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:03 INFO - 'work_dir': 'build'} 07:49:03 INFO - [mozharness: 2016-12-26 15:49:03.761919Z] Running clobber step. 07:49:03 INFO - Running pre-action listener: _resource_record_pre_action 07:49:03 INFO - Running main action method: clobber 07:49:03 INFO - rmtree: /builds/slave/test/build 07:49:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:04 INFO - Running post-action listener: _resource_record_post_action 07:49:04 INFO - [mozharness: 2016-12-26 15:49:04.225580Z] Finished clobber step (success) 07:49:04 INFO - [mozharness: 2016-12-26 15:49:04.225681Z] Running read-buildbot-config step. 07:49:04 INFO - Running pre-action listener: _resource_record_pre_action 07:49:04 INFO - Running main action method: read_buildbot_config 07:49:04 INFO - Using buildbot properties: 07:49:04 INFO - { 07:49:04 INFO - "project": "", 07:49:04 INFO - "product": "firefox", 07:49:04 INFO - "script_repo_revision": "production", 07:49:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:49:04 INFO - "repository": "", 07:49:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:49:04 INFO - "buildid": "20161226064736", 07:49:04 INFO - "pgo_build": "False", 07:49:04 INFO - "basedir": "/builds/slave/test", 07:49:04 INFO - "buildnumber": 357, 07:49:04 INFO - "slavename": "talos-linux64-ix-007", 07:49:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:49:04 INFO - "platform": "ubuntu64_hw", 07:49:04 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:49:04 INFO - "branch": "mozilla-central", 07:49:04 INFO - "repo_path": "mozilla-central", 07:49:04 INFO - "revision": "1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9", 07:49:04 INFO - "stage_platform": "linux64", 07:49:04 INFO - "builduid": "5cbeebde563446c784c3a4c58c2aa6d3", 07:49:04 INFO - "slavebuilddir": "test" 07:49:04 INFO - } 07:49:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:49:04 INFO - Running post-action listener: _resource_record_post_action 07:49:04 INFO - [mozharness: 2016-12-26 15:49:04.228118Z] Finished read-buildbot-config step (success) 07:49:04 INFO - [mozharness: 2016-12-26 15:49:04.228207Z] Running download-and-extract step. 07:49:04 INFO - Running pre-action listener: _resource_record_pre_action 07:49:04 INFO - Running main action method: download_and_extract 07:49:04 INFO - mkdir: /builds/slave/test/build/tests 07:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:04 INFO - trying https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:49:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/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 07:49:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/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 07:49:04 INFO - Downloaded 1361 bytes. 07:49:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:49:04 INFO - Using the following test package requirements: 07:49:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:49:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:49:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'jsshell-linux-x86_64.zip'], 07:49:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:49:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:49:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:49:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:49:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:49:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:49:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:49:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:49:05 INFO - Expected file size: 20241340 07:49:05 INFO - Obtained file size: 20241340 07:49:09 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 07:49:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:49:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:49:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:49:10 INFO - Expected file size: 20241340 07:49:10 INFO - Obtained file size: 20241340 07:49:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:49:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:49:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:49:15 INFO - Expected file size: 14409776 07:49:15 INFO - Obtained file size: 14409776 07:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:15 INFO - trying https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/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 07:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/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 07:49:17 INFO - Downloaded 55897654 bytes. 07:49:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:17 INFO - mkdir: /builds/slave/test/properties 07:49:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:49:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:49:17 INFO - Contents: 07:49:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:49:17 INFO - Running post-action listener: _resource_record_post_action 07:49:17 INFO - Running post-action listener: set_extra_try_arguments 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.689836Z] Finished download-and-extract step (success) 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.690134Z] Running populate-webroot step. 07:49:17 INFO - Running pre-action listener: _resource_record_pre_action 07:49:17 INFO - Running main action method: populate_webroot 07:49:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:49:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:49:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:49:17 INFO - '--tppagecycles', 07:49:17 INFO - '7'], 07:49:17 INFO - 'tests': ['tsvgm']}}, 07:49:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:49:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:49:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:49:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:49:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:49:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['damp', 'tps']}, 07:49:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'tests': ['damp', 'tps']}, 07:49:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['dromaeo_dom']}, 07:49:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:49:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['basic_compositor_video']}, 07:49:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:49:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['a11yr', 07:49:17 INFO - 'ts_paint', 07:49:17 INFO - 'tpaint', 07:49:17 INFO - 'sessionrestore', 07:49:17 INFO - 'sessionrestore_no_auto_restore', 07:49:17 INFO - 'tabpaint']}, 07:49:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tsvgx', 07:49:17 INFO - 'tsvgr_opacity', 07:49:17 INFO - 'tart', 07:49:17 INFO - 'tscrollx', 07:49:17 INFO - 'cart', 07:49:17 INFO - 'tsvg_static']}, 07:49:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:49:17 INFO - 'tsvgr_opacity', 07:49:17 INFO - 'tart', 07:49:17 INFO - 'tscrollx', 07:49:17 INFO - 'cart', 07:49:17 INFO - 'tsvg_static']}, 07:49:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s'], 07:49:17 INFO - 'tests': ['tp5o']}, 07:49:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:49:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--disable-e10s', 07:49:17 INFO - '--xperf_path', 07:49:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:17 INFO - 'tests': ['tp5n']}, 07:49:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:49:17 INFO - 'talos_options': ['--xperf_path', 07:49:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:49:17 INFO - 'tests': ['tp5n']}}, 07:49:17 INFO - 'talos.zip': {'path': '', 07:49:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:49:17 INFO - Running post-action listener: _resource_record_post_action 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.707926Z] Finished populate-webroot step (success) 07:49:17 INFO - [mozharness: 2016-12-26 15:49:17.708019Z] Running create-virtualenv step. 07:49:17 INFO - Running pre-action listener: _resource_record_pre_action 07:49:17 INFO - Running main action method: create_virtualenv 07:49:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:49:17 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 07:49:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:49:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:49:17 INFO - Using real prefix '/usr' 07:49:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:49:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:49:20 INFO - Installing pip.................done. 07:49:20 INFO - Return code: 0 07:49:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:49:20 INFO - Reading from file tmpfile_stdout 07:49:20 INFO - Output received: 07:49:20 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) 07:49:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:49:20 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')]} 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 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')]} 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:20 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 07:49:20 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 07:49:20 INFO - Using env: {'DISPLAY': ':0', 07:49:20 INFO - 'HOME': '/home/cltbld', 07:49:20 INFO - 'LANG': 'en_US.UTF-8', 07:49:20 INFO - 'LANGUAGE': 'en_US:en', 07:49:20 INFO - 'LOGNAME': 'cltbld', 07:49:20 INFO - 'MAIL': '/var/mail/cltbld', 07:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:20 INFO - 'MOZ_NO_REMOTE': '1', 07:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:20 INFO - 'NO_EM_RESTART': '1', 07:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:20 INFO - 'PWD': '/builds/slave/test', 07:49:20 INFO - 'SHELL': '/bin/bash', 07:49:20 INFO - 'SHLVL': '1', 07:49:20 INFO - 'TERM': 'linux', 07:49:20 INFO - 'TMOUT': '86400', 07:49:20 INFO - 'USER': 'cltbld', 07:49:20 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:20 INFO - '_': '/tools/buildbot/bin/python'} 07:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:20 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 07:49:20 INFO - Cleaning up... 07:49:20 INFO - Return code: 0 07:49:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:49:20 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')]} 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 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')]} 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:20 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:20 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 07:49:20 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 07:49:20 INFO - Using env: {'DISPLAY': ':0', 07:49:20 INFO - 'HOME': '/home/cltbld', 07:49:20 INFO - 'LANG': 'en_US.UTF-8', 07:49:20 INFO - 'LANGUAGE': 'en_US:en', 07:49:20 INFO - 'LOGNAME': 'cltbld', 07:49:20 INFO - 'MAIL': '/var/mail/cltbld', 07:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:20 INFO - 'MOZ_NO_REMOTE': '1', 07:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:20 INFO - 'NO_EM_RESTART': '1', 07:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:20 INFO - 'PWD': '/builds/slave/test', 07:49:20 INFO - 'SHELL': '/bin/bash', 07:49:20 INFO - 'SHLVL': '1', 07:49:20 INFO - 'TERM': 'linux', 07:49:20 INFO - 'TMOUT': '86400', 07:49:20 INFO - 'USER': 'cltbld', 07:49:20 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:20 INFO - '_': '/tools/buildbot/bin/python'} 07:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:20 INFO - Downloading/unpacking psutil>=3.1.1 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:49:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:23 INFO - Installing collected packages: psutil 07:49:23 INFO - Running setup.py install for psutil 07:49:23 INFO - building 'psutil._psutil_linux' extension 07:49:23 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 07:49:23 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 07:49:23 INFO - building 'psutil._psutil_posix' extension 07:49:23 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 07:49:23 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 07:49:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:49:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:49:23 INFO - Successfully installed psutil 07:49:23 INFO - Cleaning up... 07:49:23 INFO - Return code: 0 07:49:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:23 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:23 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 07:49:23 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 07:49:23 INFO - Using env: {'DISPLAY': ':0', 07:49:23 INFO - 'HOME': '/home/cltbld', 07:49:23 INFO - 'LANG': 'en_US.UTF-8', 07:49:23 INFO - 'LANGUAGE': 'en_US:en', 07:49:23 INFO - 'LOGNAME': 'cltbld', 07:49:23 INFO - 'MAIL': '/var/mail/cltbld', 07:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:23 INFO - 'MOZ_NO_REMOTE': '1', 07:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:23 INFO - 'NO_EM_RESTART': '1', 07:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:23 INFO - 'PWD': '/builds/slave/test', 07:49:23 INFO - 'SHELL': '/bin/bash', 07:49:23 INFO - 'SHLVL': '1', 07:49:23 INFO - 'TERM': 'linux', 07:49:23 INFO - 'TMOUT': '86400', 07:49:23 INFO - 'USER': 'cltbld', 07:49:23 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:23 INFO - '_': '/tools/buildbot/bin/python'} 07:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:49:25 INFO - Installing collected packages: mozsystemmonitor 07:49:25 INFO - Running setup.py install for mozsystemmonitor 07:49:25 INFO - Successfully installed mozsystemmonitor 07:49:25 INFO - Cleaning up... 07:49:25 INFO - Return code: 0 07:49:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:26 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:26 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 07:49:26 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 07:49:26 INFO - Using env: {'DISPLAY': ':0', 07:49:26 INFO - 'HOME': '/home/cltbld', 07:49:26 INFO - 'LANG': 'en_US.UTF-8', 07:49:26 INFO - 'LANGUAGE': 'en_US:en', 07:49:26 INFO - 'LOGNAME': 'cltbld', 07:49:26 INFO - 'MAIL': '/var/mail/cltbld', 07:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:26 INFO - 'MOZ_NO_REMOTE': '1', 07:49:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:26 INFO - 'NO_EM_RESTART': '1', 07:49:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:26 INFO - 'PWD': '/builds/slave/test', 07:49:26 INFO - 'SHELL': '/bin/bash', 07:49:26 INFO - 'SHLVL': '1', 07:49:26 INFO - 'TERM': 'linux', 07:49:26 INFO - 'TMOUT': '86400', 07:49:26 INFO - 'USER': 'cltbld', 07:49:26 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:26 INFO - '_': '/tools/buildbot/bin/python'} 07:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:26 INFO - Downloading/unpacking jsonschema==2.5.1 07:49:26 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 07:49:26 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 07:49:26 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 07:49:26 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 07:49:26 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 07:49:26 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 07:49:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:28 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 07:49:28 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 07:49:28 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 07:49:28 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 07:49:28 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 07:49:28 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 07:49:28 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:28 INFO - warning: no files found matching '*.txt' 07:49:28 INFO - no previously-included directories found matching 'build' 07:49:28 INFO - no previously-included directories found matching 'dist' 07:49:28 INFO - no previously-included directories found matching '.git*' 07:49:28 INFO - Installing collected packages: jsonschema, functools32 07:49:28 INFO - Running setup.py install for functools32 07:49:28 INFO - warning: no files found matching '*.txt' 07:49:28 INFO - no previously-included directories found matching 'build' 07:49:28 INFO - no previously-included directories found matching 'dist' 07:49:28 INFO - no previously-included directories found matching '.git*' 07:49:28 INFO - Successfully installed jsonschema functools32 07:49:28 INFO - Cleaning up... 07:49:28 INFO - Return code: 0 07:49:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:49:28 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')]} 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 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')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:28 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 07:49:28 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 07:49:28 INFO - Using env: {'DISPLAY': ':0', 07:49:28 INFO - 'HOME': '/home/cltbld', 07:49:28 INFO - 'LANG': 'en_US.UTF-8', 07:49:28 INFO - 'LANGUAGE': 'en_US:en', 07:49:28 INFO - 'LOGNAME': 'cltbld', 07:49:28 INFO - 'MAIL': '/var/mail/cltbld', 07:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:28 INFO - 'MOZ_NO_REMOTE': '1', 07:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:28 INFO - 'NO_EM_RESTART': '1', 07:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:28 INFO - 'PWD': '/builds/slave/test', 07:49:28 INFO - 'SHELL': '/bin/bash', 07:49:28 INFO - 'SHLVL': '1', 07:49:28 INFO - 'TERM': 'linux', 07:49:28 INFO - 'TMOUT': '86400', 07:49:28 INFO - 'USER': 'cltbld', 07:49:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:28 INFO - '_': '/tools/buildbot/bin/python'} 07:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:49:28 INFO - Cleaning up... 07:49:28 INFO - Return code: 0 07:49:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49:28 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')]} 07:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 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')]} 07:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:28 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:28 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 07:49:28 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 07:49:28 INFO - Using env: {'DISPLAY': ':0', 07:49:28 INFO - 'HOME': '/home/cltbld', 07:49:28 INFO - 'LANG': 'en_US.UTF-8', 07:49:28 INFO - 'LANGUAGE': 'en_US:en', 07:49:28 INFO - 'LOGNAME': 'cltbld', 07:49:28 INFO - 'MAIL': '/var/mail/cltbld', 07:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:28 INFO - 'MOZ_NO_REMOTE': '1', 07:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:28 INFO - 'NO_EM_RESTART': '1', 07:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:28 INFO - 'PWD': '/builds/slave/test', 07:49:28 INFO - 'SHELL': '/bin/bash', 07:49:28 INFO - 'SHLVL': '1', 07:49:28 INFO - 'TERM': 'linux', 07:49:28 INFO - 'TMOUT': '86400', 07:49:28 INFO - 'USER': 'cltbld', 07:49:28 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:28 INFO - '_': '/tools/buildbot/bin/python'} 07:49:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:29 INFO - Downloading/unpacking blobuploader==1.2.4 07:49:29 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 07:49:29 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 07:49:29 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 07:49:29 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 07:49:29 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 07:49:29 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 07:49:31 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 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 07:49:31 INFO - Downloading docopt-0.6.1.tar.gz 07:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:31 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:31 INFO - Running setup.py install for blobuploader 07:49:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:32 INFO - Running setup.py install for requests 07:49:32 INFO - Running setup.py install for docopt 07:49:32 INFO - Successfully installed blobuploader requests docopt 07:49:32 INFO - Cleaning up... 07:49:32 INFO - Return code: 0 07:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:32 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:32 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 07:49:32 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 07:49:32 INFO - Using env: {'DISPLAY': ':0', 07:49:32 INFO - 'HOME': '/home/cltbld', 07:49:32 INFO - 'LANG': 'en_US.UTF-8', 07:49:32 INFO - 'LANGUAGE': 'en_US:en', 07:49:32 INFO - 'LOGNAME': 'cltbld', 07:49:32 INFO - 'MAIL': '/var/mail/cltbld', 07:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:32 INFO - 'MOZ_NO_REMOTE': '1', 07:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:32 INFO - 'NO_EM_RESTART': '1', 07:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:32 INFO - 'PWD': '/builds/slave/test', 07:49:32 INFO - 'SHELL': '/bin/bash', 07:49:32 INFO - 'SHLVL': '1', 07:49:32 INFO - 'TERM': 'linux', 07:49:32 INFO - 'TMOUT': '86400', 07:49:32 INFO - 'USER': 'cltbld', 07:49:32 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:32 INFO - '_': '/tools/buildbot/bin/python'} 07:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:32 INFO - Running setup.py (path:/tmp/pip-G39rDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:32 INFO - Running setup.py (path:/tmp/pip-g5uujE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:32 INFO - Running setup.py (path:/tmp/pip-OqDehO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:32 INFO - Running setup.py (path:/tmp/pip-UoasC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:33 INFO - Running setup.py (path:/tmp/pip-Ca32VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:33 INFO - Running setup.py (path:/tmp/pip-JXVXKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:33 INFO - Running setup.py (path:/tmp/pip-jC6XPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:33 INFO - Running setup.py (path:/tmp/pip-Mlmvzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:33 INFO - Running setup.py (path:/tmp/pip-9bf_u9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:33 INFO - Running setup.py (path:/tmp/pip-0rZLuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:33 INFO - Running setup.py (path:/tmp/pip-DVVaH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:33 INFO - Running setup.py (path:/tmp/pip-Z_udpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:33 INFO - Running setup.py (path:/tmp/pip-94lpt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:33 INFO - Running setup.py (path:/tmp/pip-bUBMJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:33 INFO - Running setup.py (path:/tmp/pip-NWDtyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:33 INFO - Running setup.py (path:/tmp/pip-1sMo8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:33 INFO - Running setup.py (path:/tmp/pip-7E3r5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:33 INFO - Running setup.py install for manifestparser 07:49:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozcrash 07:49:34 INFO - Running setup.py install for mozdebug 07:49:34 INFO - Running setup.py install for mozdevice 07:49:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozfile 07:49:34 INFO - Running setup.py install for mozhttpd 07:49:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozinfo 07:49:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozInstall 07:49:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for mozleak 07:49:34 INFO - Running setup.py install for mozlog 07:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:34 INFO - Running setup.py install for moznetwork 07:49:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Running setup.py install for mozprocess 07:49:35 INFO - Running setup.py install for mozprofile 07:49:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Running setup.py install for mozrunner 07:49:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Running setup.py install for mozscreenshot 07:49:35 INFO - Running setup.py install for moztest 07:49:35 INFO - Running setup.py install for mozversion 07:49:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:35 INFO - Cleaning up... 07:49:35 INFO - Return code: 0 07:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:35 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:35 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 07:49:35 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 07:49:35 INFO - Using env: {'DISPLAY': ':0', 07:49:35 INFO - 'HOME': '/home/cltbld', 07:49:35 INFO - 'LANG': 'en_US.UTF-8', 07:49:35 INFO - 'LANGUAGE': 'en_US:en', 07:49:35 INFO - 'LOGNAME': 'cltbld', 07:49:35 INFO - 'MAIL': '/var/mail/cltbld', 07:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:35 INFO - 'MOZ_NO_REMOTE': '1', 07:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:35 INFO - 'NO_EM_RESTART': '1', 07:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:35 INFO - 'PWD': '/builds/slave/test', 07:49:35 INFO - 'SHELL': '/bin/bash', 07:49:35 INFO - 'SHLVL': '1', 07:49:35 INFO - 'TERM': 'linux', 07:49:35 INFO - 'TMOUT': '86400', 07:49:35 INFO - 'USER': 'cltbld', 07:49:35 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:35 INFO - '_': '/tools/buildbot/bin/python'} 07:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:35 INFO - Running setup.py (path:/tmp/pip-Lincz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:36 INFO - Running setup.py (path:/tmp/pip-98P57u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:36 INFO - Running setup.py (path:/tmp/pip-x8ZzsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:36 INFO - Running setup.py (path:/tmp/pip-c8Wpe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:36 INFO - Running setup.py (path:/tmp/pip-3ONHp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:36 INFO - Running setup.py (path:/tmp/pip-Xv2Ndh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:36 INFO - Running setup.py (path:/tmp/pip-h9Qupc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:36 INFO - Running setup.py (path:/tmp/pip-8EHLbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:36 INFO - Running setup.py (path:/tmp/pip-7oROqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:36 INFO - Running setup.py (path:/tmp/pip-W6kN82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:36 INFO - Running setup.py (path:/tmp/pip-3G3Ihc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:36 INFO - Running setup.py (path:/tmp/pip-Iv0Qd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:36 INFO - Running setup.py (path:/tmp/pip-9bfC1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:36 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)) 07:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:36 INFO - Running setup.py (path:/tmp/pip-roH36m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:37 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)) 07:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:37 INFO - Running setup.py (path:/tmp/pip-s7WaKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:37 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)) 07:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:37 INFO - Running setup.py (path:/tmp/pip-NEF_vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:37 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)) 07:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:37 INFO - Running setup.py (path:/tmp/pip-tdE5qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:37 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)) 07:49:37 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)) 07:49:37 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)) 07:49:37 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)) 07:49:37 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)) 07:49:37 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)) 07:49:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:37 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 07:49:39 INFO - Downloading blessings-1.6.tar.gz 07:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:49:39 INFO - Installing collected packages: blessings 07:49:39 INFO - Running setup.py install for blessings 07:49:39 INFO - Successfully installed blessings 07:49:39 INFO - Cleaning up... 07:49:39 INFO - Return code: 0 07:49:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:39 INFO - Reading from file tmpfile_stdout 07:49:39 INFO - Current package versions: 07:49:39 INFO - argparse == 1.2.1 07:49:39 INFO - blessings == 1.6 07:49:39 INFO - blobuploader == 1.2.4 07:49:39 INFO - docopt == 0.6.1 07:49:39 INFO - functools32 == 3.2.3-2 07:49:39 INFO - jsonschema == 2.5.1 07:49:39 INFO - manifestparser == 1.1 07:49:39 INFO - mozInstall == 1.12 07:49:39 INFO - mozcrash == 1.0 07:49:39 INFO - mozdebug == 0.1 07:49:39 INFO - mozdevice == 0.49 07:49:39 INFO - mozfile == 1.2 07:49:39 INFO - mozhttpd == 0.7 07:49:39 INFO - mozinfo == 0.9 07:49:39 INFO - mozleak == 0.1 07:49:39 INFO - mozlog == 3.4 07:49:39 INFO - moznetwork == 0.27 07:49:39 INFO - mozprocess == 0.23 07:49:39 INFO - mozprofile == 0.28 07:49:39 INFO - mozrunner == 6.13 07:49:39 INFO - mozscreenshot == 0.1 07:49:39 INFO - mozsystemmonitor == 0.3 07:49:39 INFO - moztest == 0.7 07:49:39 INFO - mozversion == 1.4 07:49:39 INFO - psutil == 3.1.1 07:49:39 INFO - requests == 1.2.3 07:49:39 INFO - wsgiref == 0.1.2 07:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 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 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:39 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 07:49:39 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 07:49:39 INFO - Using env: {'DISPLAY': ':0', 07:49:39 INFO - 'HOME': '/home/cltbld', 07:49:39 INFO - 'LANG': 'en_US.UTF-8', 07:49:39 INFO - 'LANGUAGE': 'en_US:en', 07:49:39 INFO - 'LOGNAME': 'cltbld', 07:49:39 INFO - 'MAIL': '/var/mail/cltbld', 07:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:39 INFO - 'MOZ_NO_REMOTE': '1', 07:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:39 INFO - 'NO_EM_RESTART': '1', 07:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:39 INFO - 'PWD': '/builds/slave/test', 07:49:39 INFO - 'SHELL': '/bin/bash', 07:49:39 INFO - 'SHLVL': '1', 07:49:39 INFO - 'TERM': 'linux', 07:49:39 INFO - 'TMOUT': '86400', 07:49:39 INFO - 'USER': 'cltbld', 07:49:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:39 INFO - '_': '/tools/buildbot/bin/python'} 07:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 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)) 07:49:39 INFO - Cleaning up... 07:49:39 INFO - Return code: 0 07:49:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:49:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2984a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2be6630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, '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': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', '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 07:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:49:39 INFO - Using env: {'DISPLAY': ':0', 07:49:39 INFO - 'HOME': '/home/cltbld', 07:49:39 INFO - 'LANG': 'en_US.UTF-8', 07:49:39 INFO - 'LANGUAGE': 'en_US:en', 07:49:39 INFO - 'LOGNAME': 'cltbld', 07:49:39 INFO - 'MAIL': '/var/mail/cltbld', 07:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:39 INFO - 'MOZ_NO_REMOTE': '1', 07:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:39 INFO - 'NO_EM_RESTART': '1', 07:49:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:39 INFO - 'PWD': '/builds/slave/test', 07:49:39 INFO - 'SHELL': '/bin/bash', 07:49:39 INFO - 'SHLVL': '1', 07:49:39 INFO - 'TERM': 'linux', 07:49:39 INFO - 'TMOUT': '86400', 07:49:39 INFO - 'USER': 'cltbld', 07:49:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:39 INFO - '_': '/tools/buildbot/bin/python'} 07:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:49:40 INFO - Cleaning up... 07:49:40 INFO - Return code: 0 07:49:40 INFO - Running post-action listener: _resource_record_post_action 07:49:40 INFO - Running post-action listener: _start_resource_monitoring 07:49:40 INFO - Starting resource monitoring. 07:49:40 INFO - [mozharness: 2016-12-26 15:49:40.188060Z] Finished create-virtualenv step (success) 07:49:40 INFO - [mozharness: 2016-12-26 15:49:40.188548Z] Running install step. 07:49:40 INFO - Running pre-action listener: _resource_record_pre_action 07:49:40 INFO - Running main action method: install 07:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:40 INFO - Reading from file tmpfile_stdout 07:49:40 INFO - Detecting whether we're running mozinstall >=1.0... 07:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:49:40 INFO - Reading from file tmpfile_stdout 07:49:40 INFO - Output received: 07:49:40 INFO - Usage: mozinstall [options] installer 07:49:40 INFO - Options: 07:49:40 INFO - -h, --help show this help message and exit 07:49:40 INFO - -d DEST, --destination=DEST 07:49:40 INFO - Directory to install application into. [default: 07:49:40 INFO - "/builds/slave/test"] 07:49:40 INFO - --app=APP Application being installed. [default: firefox] 07:49:40 INFO - mkdir: /builds/slave/test/build/application 07:49:40 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'] 07:49:40 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 07:49:56 INFO - Reading from file tmpfile_stdout 07:49:56 INFO - Output received: 07:49:56 INFO - /builds/slave/test/build/application/firefox/firefox 07:49:56 INFO - Running post-action listener: _resource_record_post_action 07:49:56 INFO - [mozharness: 2016-12-26 15:49:56.566247Z] Finished install step (success) 07:49:56 INFO - [mozharness: 2016-12-26 15:49:56.566499Z] Running run-tests step. 07:49:56 INFO - Running pre-action listener: _resource_record_pre_action 07:49:56 INFO - Running main action method: run_tests 07:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:49:56 INFO - Python 2.7.3 07:49:56 INFO - Return code: 0 07:49:56 INFO - grabbing minidump binary from tooltool 07:49:56 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')]} 07:49:56 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 0x2c6f850>, '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 0x2be46d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2be4b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:49:56 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 07:49:56 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 07:49:56 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 07:49:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:49:56 INFO - Return code: 0 07:49:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:49:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:49:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:49:56 INFO - Using env: {'DISPLAY': ':0', 07:49:56 INFO - 'HOME': '/home/cltbld', 07:49:56 INFO - 'LANG': 'en_US.UTF-8', 07:49:56 INFO - 'LANGUAGE': 'en_US:en', 07:49:56 INFO - 'LOGNAME': 'cltbld', 07:49:56 INFO - 'MAIL': '/var/mail/cltbld', 07:49:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:49:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:56 INFO - 'MOZ_NO_REMOTE': '1', 07:49:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:49:56 INFO - 'NO_EM_RESTART': '1', 07:49:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:56 INFO - 'PWD': '/builds/slave/test', 07:49:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:49:56 INFO - 'SHELL': '/bin/bash', 07:49:56 INFO - 'SHLVL': '1', 07:49:56 INFO - 'TERM': 'linux', 07:49:56 INFO - 'TMOUT': '86400', 07:49:56 INFO - 'USER': 'cltbld', 07:49:56 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808', 07:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:56 INFO - '_': '/tools/buildbot/bin/python'} 07:49:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:49:56 INFO - mozversion application_buildid: 20161226064736 07:49:56 INFO - mozversion application_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:49:56 INFO - mozversion application_display_name: Nightly 07:49:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:49:56 INFO - mozversion application_name: Firefox 07:49:56 INFO - mozversion application_remotingname: firefox 07:49:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:49:56 INFO - mozversion application_vendor: Mozilla 07:49:56 INFO - mozversion application_version: 53.0a1 07:49:56 INFO - mozversion platform_buildid: 20161226064736 07:49:56 INFO - mozversion platform_changeset: 1747e26ee37ba134c34b77b8e9099ab0e2cf2fd9 07:49:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:49:56 INFO - mozversion platform_version: 53.0a1 07:49:56 INFO - using testdate: 1482767396 07:49:56 INFO - actual date: 1482767396 07:49:56 INFO - starting webserver on 'localhost:52711' 07:49:56 INFO - SUITE-START | Running 2 tests 07:49:56 INFO - TEST-START | dromaeo_css 07:49:56 INFO - operating with platform_type : linux_ 07:49:56 INFO - Initialising browser for dromaeo_css test... 07:49:56 INFO - TEST-INFO | started process 10940 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6MamsC/profile http://localhost:52711/getInfo.html) 07:49:57 INFO - PROCESS | 10940 | 1482767397383 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 07:50:00 INFO - PROCESS | 10940 | __metrics Screen width/height:1600/1200 07:50:00 INFO - PROCESS | 10940 | colorDepth:24 07:50:00 INFO - PROCESS | 10940 | Browser inner width/height: 1024/697 07:50:00 INFO - PROCESS | 10940 | __metrics 07:50:01 INFO - PROCESS | 10940 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:50:01 INFO - PROCESS | 10940 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:01 INFO - PROCESS | 10940 | console.error: PushService: 07:50:01 INFO - PROCESS | 10940 | stateChangeProcessEnqueue: Error transitioning state 07:50:01 INFO - PROCESS | 10940 | UnknownError 07:50:02 INFO - TEST-INFO | 10940: exit 0 07:50:02 INFO - Browser initialized. 07:50:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:50:02 INFO - TEST-INFO | started process 11024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6MamsC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:14 INFO - PROCESS | 11024 | RSS: Main: 151494656 07:50:14 INFO - PROCESS | 11024 | 07:53:18 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52711/tests/dromaeo/cssquery-ext.html) 07:53:20 INFO - PROCESS | 11024 | RSS: Main: 274206720 07:53:20 INFO - PROCESS | 11024 | 07:55:03 INFO - PROCESS | 11024 | ************************* 07:55:03 INFO - PROCESS | 11024 | A coding exception was thrown and uncaught in a Task. 07:55:03 INFO - PROCESS | 11024 | 07:55:03 INFO - PROCESS | 11024 | Full message: ReferenceError: fetch is not defined 07:55:03 INFO - PROCESS | 11024 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:55:03 INFO - PROCESS | 11024 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:55:03 INFO - PROCESS | 11024 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:55:03 INFO - PROCESS | 11024 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:55:03 INFO - PROCESS | 11024 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:55:03 INFO - PROCESS | 11024 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:55:03 INFO - PROCESS | 11024 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:55:03 INFO - PROCESS | 11024 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:55:03 INFO - PROCESS | 11024 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:55:03 INFO - PROCESS | 11024 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:55:03 INFO - PROCESS | 11024 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:55:03 INFO - PROCESS | 11024 | 07:55:03 INFO - PROCESS | 11024 | ************************* 07:56:23 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-ext.html (next: http://localhost:52711/tests/dromaeo/cssquery-jquery.html) 07:56:24 INFO - PROCESS | 11024 | RSS: Main: 239960064 07:56:24 INFO - PROCESS | 11024 | 07:59:26 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52711/tests/dromaeo/cssquery-mootools.html) 07:59:27 INFO - PROCESS | 11024 | RSS: Main: 185909248 07:59:27 INFO - PROCESS | 11024 | 08:02:29 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52711/tests/dromaeo/cssquery-prototype.html) 08:02:30 INFO - PROCESS | 11024 | RSS: Main: 182194176 08:02:30 INFO - PROCESS | 11024 | 08:05:33 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52711/tests/dromaeo/cssquery-yui.html) 08:05:33 INFO - PROCESS | 11024 | RSS: Main: 185774080 08:05:33 INFO - PROCESS | 11024 | 08:08:36 INFO - PROCESS | 11024 | Cycle 1(1): loaded http://localhost:52711/tests/dromaeo/cssquery-yui.html (next: http://localhost:52711/tests/dromaeo/cssquery-dojo.html) 08:08:37 INFO - PROCESS | 11024 | RSS: Main: 270860288 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | __start_tp_report 08:08:37 INFO - PROCESS | 11024 | _x_x_mozilla_page_load 08:08:37 INFO - PROCESS | 11024 | _x_x_mozilla_page_load_details 08:08:37 INFO - PROCESS | 11024 | |i|pagename|runs| 08:08:37 INFO - PROCESS | 11024 | |0;dojo.html;2561.923142305731;2592.105723525383;2614.1896012236207;2621.868906554672;2622.1346955504673;4731.57415832575;4781.952180478194;4805.951940480594;4814.78333474994;4836;4805.519448055202;4819.78310976006;4831.9275210871865;4852.053849499349;4916;4373.934390984138;4400.801963911632;4435.756033418161;4479.17135329965;4483.730976141442;4134.8139333729905;4505.932411013838;4527.20773864574;4555.908881822378;4588.197065513538;4356.455443069616;4484.058347746977;4485.1254005468945;4507.842225522108;4582.541745825424;4373.081652852905;4392.05570802279;4427.446569178853;4447.1995040892625;4456.955430445686;4425.955740442587;4429.534898835608;4449.2213862574245;4480.8879778005485;4495.617872480843;4798.0164066366315;4824.734639594821;4835.960268542245;4836.153673107192;4882.5605695487575;5031.06925218832;5067.0879241736475;5092.414372347191;5124.846254612367;5162.845114646567;5171.81898633546;5177.068127737006;5186.222066690027;5198.766055527511;5217.16525355945;4830.444498882638;4836.806527738851;4840.903181936342;4846.8788280292565;4880.463149053601;5195.6622819516615;5235.869103272448;5258.290130832365;5270.868228294246;5273.156294992783;5097.847064588068;5122.718250496258;5123;5160.122779127557;5176.120059589879;5078.212877003996;5129.256257842592;5216.7913283469;5219.347581552306;5226.085435048852;5030.89938201234;5109.565686916654;5110.207917772751;5132.230165475208;5198.714070726146;5056.367954005749;5079.74601269935;5105.0810854045885;5142.408623008327;5149.073166829931;4322.891927701832;4333.934990975138;4340.283853164256;4348.673849461303;4400.317950717644;3753.962460375416;3759.9060023499633;3764.0966168119453;3794.6964242860504;3803.2773772983046;4373.409589705349;4465.821367145343;4468.374427580141;4474.127545128734;4512.278035514302;5064.987002599495;5136.691798492103;5140.897182056338;5145.614078944094;5198.740062996835;4411.117776444724;4414.977925110354;4416.492103408085;4423.159599676052;4463.910721785546;4617.792199351037;4620.745858977789;4627.583517483443;4632.374629425052;4673.042026384583;4633.814647414066;4674.088552732184;4678.719276843401;4834.758262086812;4875.756212189305;5207.973960130175;5279.498447647467;5280.60395470349;5287.68273903567;5288.101022826206;4805.5915247204375;4809.2305231162845;4810.903781924412;4812.639052071038;4858.271259311132;4881.170201065898;5106.795728170831;5108.693478391308;5112.156494178419;5152.793888244429;4943.925841112458;5112.667676601009;5155.922661160011;5169.844904652867;5251.186066159751;4920.114379411741;5001.049800537886;5030.899382012412;5049.7727602257255;5050.621203409685;4944.604431645388;5027.698338099725;5031.4213865405945;5031.471695471922;5033.798648054037;4955.182394904945;4962.652614317108;4977.104121258209;4989.301497790239;5001.899962000667;5041.495850414929;5047.545720885138;5073.594112471067;5074.213496907985;5077.213031980049;4980.05378977993;4993.076280888047;5025.246213068069;5040.269160971712;5051.040302342543 08:08:37 INFO - PROCESS | 11024 | |1;ext.html;524.5593701290916;552.9861753456162;556.5630979680951;563.509746520527;590.2091197794954;35099.82450087749;39020.219595608105;39023.414648780294;39057.804710976474;39123.80438097812;39756;40233.396499052535;40299;40362.51480901993;40391.19217615646;7466.066741657293;7613.352865006481;7681.846363072736;7768.067831860185;7792.571408572526;10172.084512393883;10468.371897686164;10476;10477.68566942993;10498.160147188206;9707.369021013646;9733.17268032218;9745.122938935494;9746.610135594568;9751.804963900719;7595.734149304803;7755.534667919943;8497.66009359628;8600.698975535857;9805.411675299505;9631.566579503939;9928.255380846465;9930.106290433823;9932.255080868963;9951.303408761389;38465.61534384648;39063.609363906566;39070.60929390726;39139.8043009786;39147.21705565901;3622.873199438033;3641.8725344613076;3674.7427680062406;3718.1262403335195;3735.1595890924323;27181;27567;28869.71130288712;29853.850730746424;30606.081817545288;3407.3015031918644;3525.4711793230963;3534.240138370237;3566.8038257895805;3583.372909740785;263651;269882;271139;385249;387422;53216.46783532193;53786;53877.73061134748;53905.46094539083;53916.46083539193;57273;57411.665900138374;57490.71254643783;57555.42444575501;57570;12318.137730358969;12491.188072775241;12492.312922789333;12672.049596280316;12682;7954.761357159295;8170.346372290202;8888.822223555493;9029.36794424384;9402.717918462457;18800.905995469937;18841.717374239397;18935.621287574173;18946.52633684169;19087;6803.414702413151;6939.738322988187;6978.441724662015;7091.184513780878;7110.644467776694;813.808754942588;815.176671561727;824.7855557555079;831.9916800832035;848.5714714069479;27100.86449567779;27633.723662763114;27941.301467463476;28227.15318540447;28231;949.392388871123;955.8996305387834;959.1415682963708;968.8062387522524;1055.266589720137;12723.172993755376;13500.797488037688;13748.312584370742;13791.379387927567;13795.931020345035;342635;344941;347591;347593;350067;50996.23505647418;51563.22655160176;51592.22611660828;51602.48397516052;51778.22332665013;1112.6328311657135;1175.7883580955338;1207.4385410784027;1223.7124275795181;1223.8225457308818;24210;24812.25563233106;25684.743152568983;25774.613380798928;25881.09416170425;40813.38779918363;42369.788151059045;42486;42537;42556.78721606372;319418;319976;320013;322313;322503;22763.772362276166;23602.881985589964;24838.37904052413;25874.482510350063;26040;40881.795591023045;40994;41594.376084358155;41642.167156657306;42207.155856883306;34604.65395346014;35499.46750798688;35674.46488302729;35811.28377432489;35966.82016590005;35055.09228707932;35218.47172292469;35307;35308.82345588256;35314.82342588271;33843;34036.659633403346;34081;34899.12752181114;34902;28913.42637175115;30939.226519337197;31150.22124446907;31180.53229201609;31766.52350214795;23675.64486532738;23689.503613021916;23794.40513987164;24238.636420453353;24244.272671819872 08:08:37 INFO - PROCESS | 11024 | |2;jquery.html;354.4736066940594;355.16890476285505;370.9091272638203;373.4659437005083;386.78340129527464;13346.866531334685;15736.21318934053;15930.601734956632;16418.671626567462;17337.39319123831;16817.15914204291;16871;16876.071816050113;17026.574335641617;17028.659426811457;4964.354633897588;4967.453580106186;5115.974420127913;5148.150555158413;5153.510416510426;3904.1606054698236;4100.774457404841;5034.773435195425;5129.2562578426105;5706.629069110494;5982.282126144869;6075.939240607581;6084.117802918574;6089.05619628956;6094.177286066369;5884.352721200665;5999.790007349766;6039.63762174271;6040.305364883051;6077.179580756608;5297.602679799031;5331.413544510101;5335.733213339317;5339.946600534023;5341.145416733304;19431.417057488157;19830.50423739418;19861.304854330174;20026;20084.899575502175;2055.7738648748627;2109.5991761565406;2123.6495978163584;2151.053536443975;2197.505561248722;12222.938885305606;12914.41885115172;13291.933540332333;13305.600831975056;13549.119307244997;1672.180631490573;1721.3544920654754;1745.23209787693;1776.564741638294;1778.262021261175;57593.712031439994;58968.705156474374;59063;59422.40577594212;60663;9491.952540237393;9721;9785.524760536196;9873.654422095193;9927.751806204902;13221.206727596182;13240.801387979189;13251.271180085188;13253;13270.535531256553;6135.447809697149;6292;6451.258105317855;6476.093346931433;6515.4787616990525;5654.208410822488;5683.914741278884;5816.389279125631;5817.7091145442555;5833.416658334218;6079.270487541435;6194.721237544321;6203.875922481616;6213.689315534205;6250.967970783563;1781.1094452773614;1798.379559052125;1804.9909750451163;1807.268056437145;1816.9364072257408;752.4695089961564;788.5702292250622;790.1584812175015;802.7912742686826;804.7062822070019;15823.841761582467;16383.180840957904;16464.588385290223;16505.422310219325;17143.57141071483;779.8401327727802;785.7014334552833;793.4049462902823;801.8556659801175;806.2944923192207;9172.678956236634;9724.75688107803;9781.16860066888;9852.261080418997;9932.155766759906;341109;347381;347513;347613;348593;46104;47442;61307.693461532406;61598.38401616016;63771.36228637654;896.6771962093641;901.7384958362002;923.2890674180843;930.1303281431883;932.305432452827;14338.426462941783;14392.136471811726;14726.63183420423;14839.183844888636;15326.310316035582;31530.36939261248;31855.522167167048;32034.67965320317;33295.83352083224;33299;41260;41349.1730165401;41446.58553414427;43022.569774303105;44654.553454465036;7614.771556853303;7735.497192682457;7961.761147165363;7985.8402831944195;8068.9596552016865;12085.75828483443;12152.75694486123;12261.203021803554;12278.877211227773;12661.683457913627;9955.352902061633;10290.228232882415;10444.112250458797;10448.373097614167;10821.783564328827;10397.428141452292;10438.164946804389;10652.467376631292;10800.459977001276;10805.18961077938;10016.649417270652;10243;10332;10422.322549034288;10680.145588352758;7716.92283077162;7738.535687858746;7757.7672669819995;7952.681892724227;7984.121746607984;10334.638287659896;10402.531886065297;10503.842442363219;10584.153267738717;10623 08:08:37 INFO - PROCESS | 11024 | |3;mootools.html;1281.4169552853448;1297.7469393468273;1297.7664020476311;1302.7915533514633;1311.1149973767704;6969.6166710830885;7398.519096258739;7401.555906645592;7410.5924174170395;7423.480356375056;6513.511486638497;6732.326767323265;7137.85724285514;7251.854962900738;7396.186419493852;2130.1692339987417;2170.696102545645;2171.055590817999;2172.0443005077796;2175.2386664667397;2138.818200452956;2171.6851056596784;2176.858504197222;2179.3135162423864;2182.2362173239317;1828.3509354179275;1927.3447028010473;2075.107703687414;2142.5393540388814;2154.375231182955;2117.6400011998;2123.055240418015;2124.511362386644;2125.1499400240027;2126.542793299448;2102.6845973104005;2113.482196861779;2122;2125.3517677108475;2128.2231985325398;7357.0803649543805;7408.703651853919;7410.851782964364;7443.106827180719;7446.180920098785;4308.676849236288;4437.822487100512;4438.644908407319;4439.866803995853;4440.888977775549;2869.4834929712642;3027.409655117253;3085.1361618746564;3087.8076565638426;3108.626964764243;1144.0870501016382;1179.787638225119;1211.1521934645784;1215.4591206912928;1231.993840030803;9701.6604418845;9715.36850104741;9719.514024298827;9722.756931076708;9724.513774311326;4129.359949207877;4198.6011328923705;4262.168877068942;4268.146370725867;4290.163418133434;7829.804254893673;7900.920990790133;7902.683892644345;7903.68385264583;7910.485818421784;4372.934405983913;4375.584319489643;4376.540463251376;4377.737335759864;4378.124375124988;3635.5819080806045;3738.626137386294;3942.5071866016747;4048.3725022621543;4048.4939382577177;3575.3743094958277;3609.8375573099274;3706.3314413622147;3722.813859307024;3761.1349389640536;1217.1479964025218;1225.154643296123;1236.361820587707;1247.538410787996;1250.1374051904156;766.7929658992041;798.269583331257;803.4857691077715;809.720646376999;816.2327412232482;4489.820407183676;4623.468301145412;4673.953260467351;4690.788914498856;4897.4857639947295;495.247224219192;508.54739282039014;512.2418820146205;513.6635503745053;518.8728761453392;3091.180837078176;3151.921201969969;3206.246532064963;3229.370272796782;3320.5019247113128;8267.131951145044;8727.60725767342;8780.165884240987;8798.780030499289;8808.603612837438;8779.561021948877;8785.428947118417;8793.340499462438;8793.472391656522;8796.560171991376;6686.7993960181275;6935.618540980295;6982.96508517444;6996.6151861648095;6999.12510936133;4994.275830004556;5003.374578177728;5014.5283527544425;5026.070177017264;5244.160934250502;7488.213737557478;7751.534907905544;8070.031596208492;8094.757157285291;8097.39269554774;7736.535807851547;7800.531968081933;7950.165232650488;7950.920490795249;8209.179082091743;5819.098039803837;5988.401159884151;6074.119252708244;6396.48828093742;6523.3150519194805;6715.127033485811;6893.310668933267;6925.480588955949;6932.23745388017;6932.514723969273;6358.2687990879795;6454.774082907264;6509.641969691713;6532.869342613026;6656.633885136364;6369.897933681967;6387.393197646216;6464.612123272619;6528.347165283433;6546.869062618625;5632.295963004624;5698.1452782083015;5699.800506982236;5704.743286552198;5705.258316418839;4136.937945930753;4205.5794420557695;4304;4306.138772245501;4356.237658409829;3935.4883865097354;4096.487939007624;4097.221527909687;4210.59999300066;4296.849610263625 08:08:37 INFO - PROCESS | 11024 | |4;prototype.html;355.18662263416775;359.59186323522795;360.4503132722598;365.8591442294717;366.0482744863356;4763.618910487161;4778.259369797678;4782.593479554233;4792;4850.733209673466;4771.689840160388;4777.80888764449;4790.496997815231;4791.544803243695;4805.231163013918;3704.444333349992;3733.4026555751084;3736.570294416136;3802.90492737681;3906.472626195463;3787.9810600947094;3804.6195380461872;3804.7907365094907;3804.8668296609626;3809.847606095753;3778.3576791945297;3780.773153610792;3780.9054773630605;3784.8107594620296;3790.128270497787;3599.874004409833;3763.303788799081;3783;3783.829727662262;3786.0724122590004;3292.1275861896615;3300.488424294238;3315.8673653053847;3325.2850637112897;3332.2669012817382;4677.929831052537;4683.953160468386;4691.906161876744;4698.718076915396;4705.4588722296685;4269.359596060584;4277.123189746126;4284.400183974246;4299.376590394375;4304.289792184286;4908.502906613481;4966.106100901836;4992.176290911995;5013.4485206627605;5022.698638081691;3621.112827357288;3625.909352266188;3627.2564124354444;3633.3823250047553;3642.1805093853936;5031.874203144951;5247.47525247476;5249.4750524947585;5266.8419947401635;5271;3563.7861728295866;3575.3028159508644;3659.2864391443945;3671.7429779915683;3685;3663.578688450862;3691.520102386726;3691.9356933744925;3702.7222958278235;3709.5718148513015;4181.790910454465;4213.747175169499;4253.80857861397;4286.357046443059;4288.7855607220135;4844.176489996709;4915.749269826976;4973.378327709036;5022.372203474566;5046.1926091825135;1227.5151315230435;1229.8155276708387;1236.9319687417278;1241.2118304876287;1246.2647038247478;841.8400503904238;864.9394542382099;874.1215078845719;879.3185281406961;889.0709208876739;4258.510271318776;4278.272693642089;4296.183725092223;4296.484421869333;4317.5967810461725;5004.474530174352;5006.524380183877;5006.64953453262;5009.323741294951;5028.647994640413;4249.171411574714;4261;4262.786860657016;4286.87139385819;4296.3555466680245;2515.6729625148614;2661.920142395731;2663.6670416197976;2664.6935602405583;2686.100156447612;4632.652551058684;4649.349091127245;4652.697574657636;4657.534246575316;4662.370579971728;4953.975230123826;5059.848204553869;5088.262201980692;5095.8980820383385;5164.612654050961;4547.386102876069;4570.611498022706;4622.9829437523695;4632.5830675239395;4645.5354464553275;4979.651424400257;4980.452150263541;4980.477049909708;4989.725565093955;4992.700437973734;4911.533404326583;4927.408710954708;4947.921872813355;4982.576480999156;5001.624878134082;4530.682852200446;4634.83778067766;4791.448983366958;4832.855014349575;4836.250381190921;3528.188516641238;3552.2540266544315;3659.853605855736;3663.775877228022;3668.5414323209598;5178.533931946143;5178.689278643143;5191.636585438983;5192.922106168335;5205.167173252262;4915.493098519709;5054.2671312660195;5058.974705126451;5062.2406639002975;5064.8987020258655;4964.379452568429;4987.850364489072;4996.3504744383;5000.924986125284;5009.348784657971;4900.852974410774;4903.224517121867;4924.778384972757;4954.925676114789;4969.1800852860315;4803.375561177025;4998.925016124834;5002.924956125588;5006.745615350052;5084.9745751271;4869.780859861244;4870.488598697228;4875.68308059975;4884.926726099183;4907 08:08:37 INFO - PROCESS | 11024 | |5;yui.html;673.605940524793;697.1007400453416;736.9042024536809;753.7248904149984;775.1628241499179;13254.867451325485;14379.35292911819;14420.855791442082;14428.639284017905;14547.127172369652;12144.3320617366;12175.939120304409;12177.025837932966;12185.878141218585;12196.878031219707;636.150738763751;670.3598063849037;775.7982924439658;802.3821657323842;819.2299238715618;815.5066184958104;816.7998840284139;824.8803923431099;825.8389614025267;826.0954255090666;866.4194989357114;867.7266661001796;870.5864714260707;870.9042005379404;871.3116637856086;674.7806962737081;710.8045287545915;815.0463957170066;859.325429537813;860.7417774667574;627.3506920337429;627.3788948940562;629.2857606616477;634.1344065350763;634.3624657219511;13969.3713782879;13979.301034948314;14002.789958150635;14004.929975350058;14008.369623366973;11860.64268853218;12051.216670916363;12232.877671223263;12578.874211257953;12604;12109;12244.142909996304;12357.045745361487;12619;12814.16707913992;4158.209940111369;4207.305794543897;4254.446921900164;4365.43249377582;4379.08039311745;40756.59243407587;64587.3541264586;64783;65484.67257663729;66251.33748662547;9928.751781205383;9940.652077177409;10044.598216071421;10093.385058390622;10270.281080324456;17584.56038599045;17696.55758606045;17723.379681711336;17741.822581774017;18923.43229703111;6078.635281883101;6120.908186377208;6129.233845769279;6159.014557670751;6173.81478555644;3268.722158616544;3304.3556506481004;3329.467285234351;3353.329334133134;3423.9657603424143;2882.4523340565643;2894.956575651367;2943.7644988400875;2948.469275530425;2961.1116665000413;491.1674711364203;496.27543786072016;503.3984388655521;505.7926250237385;507.7029937486612;410.93928273314043;427.6258274010241;435.0667817531378;454.43422938441194;458.85546052993203;6920.204176519664;7021.122359705037;7038.155421349471;7148.428125749928;7316.780496585111;429.49534297200665;435.3100335967527;454.63856234293814;466.09344824317054;472.4897111119982;3424.5548078749603;3475.357058944053;3480.9825950870086;3559.94660080099;3617.312710585033;67754.32245677579;68781.96827047598;68957.31042689609;70395;70581;60342.69828650829;60807;61085.38914610886;61477.38522614806;61897.69051154715;44444.55555444534;53374.73312633412;53455.46544534505;53668.463315367124;56676.43323566794;8196.713189355785;9085.863712044193;9108.590113445043;9159.633614655339;9351.719448416557;29598;34324.48513272353;35308;35820.462693060144;37390.25219495541;33066.17334566655;34327.31345373028;38552.42171367488;47602.76198618985;47697.76151119222;10440.164786817188;10446.477676116012;10654;10657.893421066;10752.623658171926;10840.728981775519;11116.498787772687;11313.151513636345;11409.885901140882;11547.769044618892;11257.49341279628;11457;11462;11712.355820429959;12237.20458170216;10791.56833726642;11179.55281788752;11284.717882053013;11340.432978351215;11405.714857128638;11242.55029798799;11260.88739112631;11872.643820685393;12206.389680515762;12206.87793122093;9177;9200.585973631336;9427.717168484956;9742.512874356396;9801.460919649488;9902.554385052834;10069.69790906274;10075.596976120873;10088.192944564564;10128.44293563861 08:08:37 INFO - PROCESS | 11024 | __end_tp_report 08:08:37 INFO - PROCESS | 11024 | __start_cc_report 08:08:37 INFO - PROCESS | 11024 | _x_x_mozilla_cycle_collect,2180 08:08:37 INFO - PROCESS | 11024 | __end_cc_report 08:08:37 INFO - PROCESS | 11024 | __startTimestamp1482768517235__endTimestamp 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | ------- Summary: start ------- 08:08:37 INFO - PROCESS | 11024 | Number of tests: 6 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#0] dojo.html Cycles:165 Average:4761.67 Median:4880.46 stddev:514.15 (10.5%) stddev-sans-first:485.90 08:08:37 INFO - PROCESS | 11024 | Values: 2561.9 2592.1 2614.2 2621.9 2622.1 4731.6 4782.0 4806.0 4814.8 4836.0 4805.5 4819.8 4831.9 4852.1 4916.0 4373.9 4400.8 4435.8 4479.2 4483.7 4134.8 4505.9 4527.2 4555.9 4588.2 4356.5 4484.1 4485.1 4507.8 4582.5 4373.1 4392.1 4427.4 4447.2 4457.0 4426.0 4429.5 4449.2 4480.9 4495.6 4798.0 4824.7 4836.0 4836.2 4882.6 5031.1 5067.1 5092.4 5124.8 5162.8 5171.8 5177.1 5186.2 5198.8 5217.2 4830.4 4836.8 4840.9 4846.9 4880.5 5195.7 5235.9 5258.3 5270.9 5273.2 5097.8 5122.7 5123.0 5160.1 5176.1 5078.2 5129.3 5216.8 5219.3 5226.1 5030.9 5109.6 5110.2 5132.2 5198.7 5056.4 5079.7 5105.1 5142.4 5149.1 4322.9 4333.9 4340.3 4348.7 4400.3 3754.0 3759.9 3764.1 3794.7 3803.3 4373.4 4465.8 4468.4 4474.1 4512.3 5065.0 5136.7 5140.9 5145.6 5198.7 4411.1 4415.0 4416.5 4423.2 4463.9 4617.8 4620.7 4627.6 4632.4 4673.0 4633.8 4674.1 4678.7 4834.8 4875.8 5208.0 5279.5 5280.6 5287.7 5288.1 4805.6 4809.2 4810.9 4812.6 4858.3 4881.2 5106.8 5108.7 5112.2 5152.8 4943.9 5112.7 5155.9 5169.8 5251.2 4920.1 5001.0 5030.9 5049.8 5050.6 4944.6 5027.7 5031.4 5031.5 5033.8 4955.2 4962.7 4977.1 4989.3 5001.9 5041.5 5047.5 5073.6 5074.2 5077.2 4980.1 4993.1 5025.2 5040.3 5051.0 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#1] ext.html Cycles:180 Average:48070.90 Median:3632.37 stddev:86687.61 (2386.5%) stddev-sans-first:86857.29 08:08:37 INFO - PROCESS | 11024 | Values: 524.6 553.0 556.6 563.5 590.2 35099.8 39020.2 39023.4 39057.8 39123.8 39756.0 40233.4 40299.0 40362.5 40391.2 7466.1 7613.4 7681.8 7768.1 7792.6 10172.1 10468.4 10476.0 10477.7 10498.2 9707.4 9733.2 9745.1 9746.6 9751.8 7595.7 7755.5 8497.7 8600.7 9805.4 9631.6 9928.3 9930.1 9932.3 9951.3 38465.6 39063.6 39070.6 39139.8 39147.2 3622.9 3641.9 3674.7 3718.1 3735.2 27181.0 27567.0 28869.7 29853.9 30606.1 3407.3 3525.5 3534.2 3566.8 3583.4 263651.0 269882.0 271139.0 385249.0 387422.0 53216.5 53786.0 53877.7 53905.5 53916.5 57273.0 57411.7 57490.7 57555.4 57570.0 12318.1 12491.2 12492.3 12672.0 12682.0 7954.8 8170.3 8888.8 9029.4 9402.7 18800.9 18841.7 18935.6 18946.5 19087.0 6803.4 6939.7 6978.4 7091.2 7110.6 813.8 815.2 824.8 832.0 848.6 27100.9 27633.7 27941.3 28227.2 28231.0 949.4 955.9 959.1 968.8 1055.3 12723.2 13500.8 13748.3 13791.4 13795.9 342635.0 344941.0 347591.0 347593.0 350067.0 50996.2 51563.2 51592.2 51602.5 51778.2 1112.6 1175.8 1207.4 1223.7 1223.8 24210.0 24812.3 25684.7 25774.6 25881.1 40813.4 42369.8 42486.0 42537.0 42556.8 319418.0 319976.0 320013.0 322313.0 322503.0 22763.8 23602.9 24838.4 25874.5 26040.0 40881.8 40994.0 41594.4 41642.2 42207.2 34604.7 35499.5 35674.5 35811.3 35966.8 35055.1 35218.5 35307.0 35308.8 35314.8 33843.0 34036.7 34081.0 34899.1 34902.0 28913.4 30939.2 31150.2 31180.5 31766.5 23675.6 23689.5 23794.4 24238.6 24244.3 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#2] jquery.html Cycles:180 Average:21938.45 Median:22680.39 stddev:56723.30 (250.1%) stddev-sans-first:56859.28 08:08:37 INFO - PROCESS | 11024 | Values: 354.5 355.2 370.9 373.5 386.8 13346.9 15736.2 15930.6 16418.7 17337.4 16817.2 16871.0 16876.1 17026.6 17028.7 4964.4 4967.5 5116.0 5148.2 5153.5 3904.2 4100.8 5034.8 5129.3 5706.6 5982.3 6075.9 6084.1 6089.1 6094.2 5884.4 5999.8 6039.6 6040.3 6077.2 5297.6 5331.4 5335.7 5339.9 5341.1 19431.4 19830.5 19861.3 20026.0 20084.9 2055.8 2109.6 2123.6 2151.1 2197.5 12222.9 12914.4 13291.9 13305.6 13549.1 1672.2 1721.4 1745.2 1776.6 1778.3 57593.7 58968.7 59063.0 59422.4 60663.0 9492.0 9721.0 9785.5 9873.7 9927.8 13221.2 13240.8 13251.3 13253.0 13270.5 6135.4 6292.0 6451.3 6476.1 6515.5 5654.2 5683.9 5816.4 5817.7 5833.4 6079.3 6194.7 6203.9 6213.7 6251.0 1781.1 1798.4 1805.0 1807.3 1816.9 752.5 788.6 790.2 802.8 804.7 15823.8 16383.2 16464.6 16505.4 17143.6 779.8 785.7 793.4 801.9 806.3 9172.7 9724.8 9781.2 9852.3 9932.2 341109.0 347381.0 347513.0 347613.0 348593.0 46104.0 47442.0 61307.7 61598.4 63771.4 896.7 901.7 923.3 930.1 932.3 14338.4 14392.1 14726.6 14839.2 15326.3 31530.4 31855.5 32034.7 33295.8 33299.0 41260.0 41349.2 41446.6 43022.6 44654.6 7614.8 7735.5 7961.8 7985.8 8069.0 12085.8 12152.8 12261.2 12278.9 12661.7 9955.4 10290.2 10444.1 10448.4 10821.8 10397.4 10438.2 10652.5 10800.5 10805.2 10016.6 10243.0 10332.0 10422.3 10680.1 7716.9 7738.5 7757.8 7952.7 7984.1 10334.6 10402.5 10503.8 10584.2 10623.0 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#3] mootools.html Cycles:180 Average:4772.92 Median:2744.76 stddev:2574.32 (93.8%) stddev-sans-first:2568.16 08:08:37 INFO - PROCESS | 11024 | Values: 1281.4 1297.7 1297.8 1302.8 1311.1 6969.6 7398.5 7401.6 7410.6 7423.5 6513.5 6732.3 7137.9 7251.9 7396.2 2130.2 2170.7 2171.1 2172.0 2175.2 2138.8 2171.7 2176.9 2179.3 2182.2 1828.4 1927.3 2075.1 2142.5 2154.4 2117.6 2123.1 2124.5 2125.1 2126.5 2102.7 2113.5 2122.0 2125.4 2128.2 7357.1 7408.7 7410.9 7443.1 7446.2 4308.7 4437.8 4438.6 4439.9 4440.9 2869.5 3027.4 3085.1 3087.8 3108.6 1144.1 1179.8 1211.2 1215.5 1232.0 9701.7 9715.4 9719.5 9722.8 9724.5 4129.4 4198.6 4262.2 4268.1 4290.2 7829.8 7900.9 7902.7 7903.7 7910.5 4372.9 4375.6 4376.5 4377.7 4378.1 3635.6 3738.6 3942.5 4048.4 4048.5 3575.4 3609.8 3706.3 3722.8 3761.1 1217.1 1225.2 1236.4 1247.5 1250.1 766.8 798.3 803.5 809.7 816.2 4489.8 4623.5 4674.0 4690.8 4897.5 495.2 508.5 512.2 513.7 518.9 3091.2 3151.9 3206.2 3229.4 3320.5 8267.1 8727.6 8780.2 8798.8 8808.6 8779.6 8785.4 8793.3 8793.5 8796.6 6686.8 6935.6 6983.0 6996.6 6999.1 4994.3 5003.4 5014.5 5026.1 5244.2 7488.2 7751.5 8070.0 8094.8 8097.4 7736.5 7800.5 7950.2 7950.9 8209.2 5819.1 5988.4 6074.1 6396.5 6523.3 6715.1 6893.3 6925.5 6932.2 6932.5 6358.3 6454.8 6509.6 6532.9 6656.6 6369.9 6387.4 6464.6 6528.3 6546.9 5632.3 5698.1 5699.8 5704.7 5705.3 4136.9 4205.6 4304.0 4306.1 4356.2 3935.5 4096.5 4097.2 4210.6 4296.8 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#4] prototype.html Cycles:180 Average:4128.26 Median:4659.95 stddev:1182.00 (25.4%) stddev-sans-first:1150.89 08:08:37 INFO - PROCESS | 11024 | Values: 355.2 359.6 360.5 365.9 366.0 4763.6 4778.3 4782.6 4792.0 4850.7 4771.7 4777.8 4790.5 4791.5 4805.2 3704.4 3733.4 3736.6 3802.9 3906.5 3788.0 3804.6 3804.8 3804.9 3809.8 3778.4 3780.8 3780.9 3784.8 3790.1 3599.9 3763.3 3783.0 3783.8 3786.1 3292.1 3300.5 3315.9 3325.3 3332.3 4677.9 4684.0 4691.9 4698.7 4705.5 4269.4 4277.1 4284.4 4299.4 4304.3 4908.5 4966.1 4992.2 5013.4 5022.7 3621.1 3625.9 3627.3 3633.4 3642.2 5031.9 5247.5 5249.5 5266.8 5271.0 3563.8 3575.3 3659.3 3671.7 3685.0 3663.6 3691.5 3691.9 3702.7 3709.6 4181.8 4213.7 4253.8 4286.4 4288.8 4844.2 4915.7 4973.4 5022.4 5046.2 1227.5 1229.8 1236.9 1241.2 1246.3 841.8 864.9 874.1 879.3 889.1 4258.5 4278.3 4296.2 4296.5 4317.6 5004.5 5006.5 5006.6 5009.3 5028.6 4249.2 4261.0 4262.8 4286.9 4296.4 2515.7 2661.9 2663.7 2664.7 2686.1 4632.7 4649.3 4652.7 4657.5 4662.4 4954.0 5059.8 5088.3 5095.9 5164.6 4547.4 4570.6 4623.0 4632.6 4645.5 4979.7 4980.5 4980.5 4989.7 4992.7 4911.5 4927.4 4947.9 4982.6 5001.6 4530.7 4634.8 4791.4 4832.9 4836.3 3528.2 3552.3 3659.9 3663.8 3668.5 5178.5 5178.7 5191.6 5192.9 5205.2 4915.5 5054.3 5059.0 5062.2 5064.9 4964.4 4987.9 4996.4 5000.9 5009.3 4900.9 4903.2 4924.8 4954.9 4969.2 4803.4 4998.9 5002.9 5006.7 5085.0 4869.8 4870.5 4875.7 4884.9 4907.0 08:08:37 INFO - PROCESS | 11024 | 08:08:37 INFO - PROCESS | 11024 | [#5] yui.html Cycles:180 Average:14696.20 Median:428.56 stddev:18642.65 (4350.1%) stddev-sans-first:18665.21 08:08:37 INFO - PROCESS | 11024 | Values: 673.6 697.1 736.9 753.7 775.2 13254.9 14379.4 14420.9 14428.6 14547.1 12144.3 12175.9 12177.0 12185.9 12196.9 636.2 670.4 775.8 802.4 819.2 815.5 816.8 824.9 825.8 826.1 866.4 867.7 870.6 870.9 871.3 674.8 710.8 815.0 859.3 860.7 627.4 627.4 629.3 634.1 634.4 13969.4 13979.3 14002.8 14004.9 14008.4 11860.6 12051.2 12232.9 12578.9 12604.0 12109.0 12244.1 12357.0 12619.0 12814.2 4158.2 4207.3 4254.4 4365.4 4379.1 40756.6 64587.4 64783.0 65484.7 66251.3 9928.8 9940.7 10044.6 10093.4 10270.3 17584.6 17696.6 17723.4 17741.8 18923.4 6078.6 6120.9 6129.2 6159.0 6173.8 3268.7 3304.4 3329.5 3353.3 3424.0 2882.5 2895.0 2943.8 2948.5 2961.1 491.2 496.3 503.4 505.8 507.7 410.9 427.6 435.1 454.4 458.9 6920.2 7021.1 7038.2 7148.4 7316.8 429.5 435.3 454.6 466.1 472.5 3424.6 3475.4 3481.0 3559.9 3617.3 67754.3 68782.0 68957.3 70395.0 70581.0 60342.7 60807.0 61085.4 61477.4 61897.7 44444.6 53374.7 53455.5 53668.5 56676.4 8196.7 9085.9 9108.6 9159.6 9351.7 29598.0 34324.5 35308.0 35820.5 37390.3 33066.2 34327.3 38552.4 47602.8 47697.8 10440.2 10446.5 10654.0 10657.9 10752.6 10840.7 11116.5 11313.2 11409.9 11547.8 11257.5 11457.0 11462.0 11712.4 12237.2 10791.6 11179.6 11284.7 11340.4 11405.7 11242.6 11260.9 11872.6 12206.4 12206.9 9177.0 9200.6 9427.7 9742.5 9801.5 9902.6 10069.7 10075.6 10088.2 10128.4 08:08:37 INFO - PROCESS | 11024 | -------- Summary: end -------- 08:08:37 INFO - PROCESS | 11024 | 08:08:38 INFO - TEST-INFO | 11024: exit 0 08:08:38 INFO - TEST-OK | dromaeo_css | took 1121256ms 08:08:38 INFO - TEST-START | kraken 08:08:38 INFO - operating with platform_type : linux_ 08:08:38 INFO - Initialising browser for kraken test... 08:08:38 INFO - TEST-INFO | started process 11326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSu0vE8/profile http://localhost:52711/getInfo.html) 08:08:38 INFO - PROCESS | 11326 | 1482768518582 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 08:08:41 INFO - PROCESS | 11326 | __metrics Screen width/height:1600/1200 08:08:41 INFO - PROCESS | 11326 | colorDepth:24 08:08:41 INFO - PROCESS | 11326 | Browser inner width/height: 1024/697 08:08:41 INFO - PROCESS | 11326 | __metrics 08:08:42 INFO - PROCESS | 11326 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:08:42 INFO - PROCESS | 11326 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:43 INFO - TEST-INFO | 11326: exit 0 08:08:43 INFO - Browser initialized. 08:08:43 INFO - Running cycle 1/1 for kraken test... 08:08:43 INFO - TEST-INFO | started process 11408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSu0vE8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:55 INFO - PROCESS | 11408 | RSS: Main: 151330816 08:08:55 INFO - PROCESS | 11408 | 08:08:58 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52711/tests/kraken/driver.html?testName=audio-beat-detection) 08:08:59 INFO - PROCESS | 11408 | RSS: Main: 205565952 08:08:59 INFO - PROCESS | 11408 | 08:09:01 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52711/tests/kraken/driver.html?testName=audio-dft) 08:09:02 INFO - PROCESS | 11408 | RSS: Main: 185221120 08:09:02 INFO - PROCESS | 11408 | 08:09:05 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52711/tests/kraken/driver.html?testName=audio-fft) 08:09:06 INFO - PROCESS | 11408 | RSS: Main: 267632640 08:09:06 INFO - PROCESS | 11408 | 08:09:08 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52711/tests/kraken/driver.html?testName=audio-oscillator) 08:09:09 INFO - PROCESS | 11408 | RSS: Main: 178106368 08:09:09 INFO - PROCESS | 11408 | 08:09:11 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52711/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:09:11 INFO - PROCESS | 11408 | RSS: Main: 182046720 08:09:11 INFO - PROCESS | 11408 | 08:09:14 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52711/tests/kraken/driver.html?testName=imaging-darkroom) 08:09:15 INFO - PROCESS | 11408 | RSS: Main: 220655616 08:09:15 INFO - PROCESS | 11408 | 08:09:19 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52711/tests/kraken/driver.html?testName=imaging-desaturate) 08:09:19 INFO - PROCESS | 11408 | RSS: Main: 221134848 08:09:19 INFO - PROCESS | 11408 | 08:09:22 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52711/tests/kraken/driver.html?testName=json-parse-financial) 08:09:23 INFO - PROCESS | 11408 | RSS: Main: 219201536 08:09:23 INFO - PROCESS | 11408 | 08:09:24 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52711/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:09:25 INFO - PROCESS | 11408 | RSS: Main: 182128640 08:09:25 INFO - PROCESS | 11408 | 08:09:27 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:27 INFO - PROCESS | 11408 | RSS: Main: 189784064 08:09:27 INFO - PROCESS | 11408 | 08:09:30 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:30 INFO - PROCESS | 11408 | RSS: Main: 198025216 08:09:30 INFO - PROCESS | 11408 | 08:09:33 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:34 INFO - PROCESS | 11408 | RSS: Main: 206069760 08:09:34 INFO - PROCESS | 11408 | 08:09:37 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:38 INFO - PROCESS | 11408 | RSS: Main: 203382784 08:09:38 INFO - PROCESS | 11408 | 08:09:39 INFO - PROCESS | 11408 | Cycle 1(1): loaded http://localhost:52711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52711/tests/kraken/driver.html?testName=ai-astar) 08:09:40 INFO - PROCESS | 11408 | RSS: Main: 254791680 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | __start_tp_report 08:09:40 INFO - PROCESS | 11408 | _x_x_mozilla_page_load 08:09:40 INFO - PROCESS | 11408 | _x_x_mozilla_page_load_details 08:09:40 INFO - PROCESS | 11408 | |i|pagename|runs| 08:09:40 INFO - PROCESS | 11408 | |0;ai-astar;112;115;113;114;114;114;114;114;113;114 08:09:40 INFO - PROCESS | 11408 | |1;audio-beat-detection;169;125;123;164;125;127;125;130;153;126 08:09:40 INFO - PROCESS | 11408 | |2;audio-dft;161;162;161;161;163;161;163;162;161;161 08:09:40 INFO - PROCESS | 11408 | |3;audio-fft;78;76;76;104;76;76;76;77;93;76 08:09:40 INFO - PROCESS | 11408 | |4;audio-oscillator;85;85;108;84;85;85;100;85;85;106 08:09:40 INFO - PROCESS | 11408 | |5;imaging-gaussian-blur;117;118;117;118;119;117;117;117;118;117 08:09:40 INFO - PROCESS | 11408 | |6;imaging-darkroom;149;149;150;149;148;148;149;150;149;149 08:09:40 INFO - PROCESS | 11408 | |7;imaging-desaturate;95;95;95;96;96;95;95;95;96;95 08:09:40 INFO - PROCESS | 11408 | |8;json-parse-financial;61;61;60;60;63;63;62;64;62;73 08:09:40 INFO - PROCESS | 11408 | |9;json-stringify-tinderbox;68;68;67;68;67;67;68;77;67;67 08:09:40 INFO - PROCESS | 11408 | |10;stanford-crypto-aes;84;84;85;86;85;87;91;87;83;84 08:09:40 INFO - PROCESS | 11408 | |11;stanford-crypto-ccm;139;145;138;134;132;133;133;135;177;134 08:09:40 INFO - PROCESS | 11408 | |12;stanford-crypto-pbkdf2;182;179;184;180;178;180;180;179;180;178 08:09:40 INFO - PROCESS | 11408 | |13;stanford-crypto-sha256-iterative;71;69;70;68;68;68;68;68;68;68 08:09:40 INFO - PROCESS | 11408 | __end_tp_report 08:09:40 INFO - PROCESS | 11408 | __start_cc_report 08:09:40 INFO - PROCESS | 11408 | _x_x_mozilla_cycle_collect,1842 08:09:40 INFO - PROCESS | 11408 | __end_cc_report 08:09:40 INFO - PROCESS | 11408 | __startTimestamp1482768580068__endTimestamp 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | ------- Summary: start ------- 08:09:40 INFO - PROCESS | 11408 | Number of tests: 14 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.60 08:09:40 INFO - PROCESS | 11408 | Values: 112.0 115.0 113.0 114.0 114.0 114.0 114.0 114.0 113.0 114.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:128.50 stddev:17.97 (14.0%) stddev-sans-first:14.78 08:09:40 INFO - PROCESS | 11408 | Values: 169.0 125.0 123.0 164.0 125.0 127.0 125.0 130.0 153.0 126.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 08:09:40 INFO - PROCESS | 11408 | Values: 161.0 162.0 161.0 161.0 163.0 161.0 163.0 162.0 161.0 161.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:9.70 (12.8%) stddev-sans-first:10.24 08:09:40 INFO - PROCESS | 11408 | Values: 78.0 76.0 76.0 104.0 76.0 76.0 76.0 77.0 93.0 76.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:85.00 stddev:9.77 (11.5%) stddev-sans-first:10.14 08:09:40 INFO - PROCESS | 11408 | Values: 85.0 85.0 108.0 84.0 85.0 85.0 100.0 85.0 85.0 106.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 08:09:40 INFO - PROCESS | 11408 | Values: 117.0 118.0 117.0 118.0 119.0 117.0 117.0 117.0 118.0 117.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 08:09:40 INFO - PROCESS | 11408 | Values: 149.0 149.0 150.0 149.0 148.0 148.0 149.0 150.0 149.0 149.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:09:40 INFO - PROCESS | 11408 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:62.50 stddev:3.78 (6.1%) stddev-sans-first:3.95 08:09:40 INFO - PROCESS | 11408 | Values: 61.0 61.0 60.0 60.0 63.0 63.0 62.0 64.0 62.0 73.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.00 stddev:3.06 (4.5%) stddev-sans-first:3.24 08:09:40 INFO - PROCESS | 11408 | Values: 68.0 68.0 67.0 68.0 67.0 67.0 68.0 77.0 67.0 67.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.50 stddev:2.32 (2.7%) stddev-sans-first:2.39 08:09:40 INFO - PROCESS | 11408 | Values: 84.0 84.0 85.0 86.0 85.0 87.0 91.0 87.0 83.0 84.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:136.50 stddev:13.57 (9.9%) stddev-sans-first:14.39 08:09:40 INFO - PROCESS | 11408 | Values: 139.0 145.0 138.0 134.0 132.0 133.0 133.0 135.0 177.0 134.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:180.00 stddev:1.83 (1.0%) stddev-sans-first:1.79 08:09:40 INFO - PROCESS | 11408 | Values: 182.0 179.0 184.0 180.0 178.0 180.0 180.0 179.0 180.0 178.0 08:09:40 INFO - PROCESS | 11408 | 08:09:40 INFO - PROCESS | 11408 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:0.71 08:09:40 INFO - PROCESS | 11408 | Values: 71.0 69.0 70.0 68.0 68.0 68.0 68.0 68.0 68.0 68.0 08:09:40 INFO - PROCESS | 11408 | -------- Summary: end -------- 08:09:40 INFO - PROCESS | 11408 | 08:09:41 INFO - TEST-INFO | 11408: exit 0 08:09:41 INFO - TEST-OK | kraken | took 63106ms 08:09:41 INFO - SUITE-END | took 1184s 08:09:41 INFO - Completed test suite (00:19:44) 08:09:41 INFO - javascript benchmark 08:09:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2561.923142305731, 2592.105723525383, 2614.1896012236207, 2621.868906554672, 2622.1346955504673, 4731.57415832575, 4781.952180478194, 4805.951940480594, 4814.78333474994, 4836.0, 4805.519448055202, 4819.78310976006, 4831.9275210871865, 4852.053849499349, 4916.0, 4373.934390984138, 4400.801963911632, 4435.756033418161, 4479.17135329965, 4483.730976141442, 4134.8139333729905, 4505.932411013838, 4527.20773864574, 4555.908881822378, 4588.197065513538, 4356.455443069616, 4484.058347746977, 4485.1254005468945, 4507.842225522108, 4582.541745825424, 4373.081652852905, 4392.05570802279, 4427.446569178853, 4447.1995040892625, 4456.955430445686, 4425.955740442587, 4429.534898835608, 4449.2213862574245, 4480.8879778005485, 4495.617872480843, 4798.0164066366315, 4824.734639594821, 4835.960268542245, 4836.153673107192, 4882.5605695487575, 5031.06925218832, 5067.0879241736475, 5092.414372347191, 5124.846254612367, 5162.845114646567, 5171.81898633546, 5177.068127737006, 5186.222066690027, 5198.766055527511, 5217.16525355945, 4830.444498882638, 4836.806527738851, 4840.903181936342, 4846.8788280292565, 4880.463149053601, 5195.6622819516615, 5235.869103272448, 5258.290130832365, 5270.868228294246, 5273.156294992783, 5097.847064588068, 5122.718250496258, 5123.0, 5160.122779127557, 5176.120059589879, 5078.212877003996, 5129.256257842592, 5216.7913283469, 5219.347581552306, 5226.085435048852, 5030.89938201234, 5109.565686916654, 5110.207917772751, 5132.230165475208, 5198.714070726146, 5056.367954005749, 5079.74601269935, 5105.0810854045885, 5142.408623008327, 5149.073166829931, 4322.891927701832, 4333.934990975138, 4340.283853164256, 4348.673849461303, 4400.317950717644, 3753.962460375416, 3759.9060023499633, 3764.0966168119453, 3794.6964242860504, 3803.2773772983046, 4373.409589705349, 4465.821367145343, 4468.374427580141, 4474.127545128734, 4512.278035514302, 5064.987002599495, 5136.691798492103, 5140.897182056338, 5145.614078944094, 5198.740062996835, 4411.117776444724, 4414.977925110354, 4416.492103408085, 4423.159599676052, 4463.910721785546, 4617.792199351037, 4620.745858977789, 4627.583517483443, 4632.374629425052, 4673.042026384583, 4633.814647414066, 4674.088552732184, 4678.719276843401, 4834.758262086812, 4875.756212189305, 5207.973960130175, 5279.498447647467, 5280.60395470349, 5287.68273903567, 5288.101022826206, 4805.5915247204375, 4809.2305231162845, 4810.903781924412, 4812.639052071038, 4858.271259311132, 4881.170201065898, 5106.795728170831, 5108.693478391308, 5112.156494178419, 5152.793888244429, 4943.925841112458, 5112.667676601009, 5155.922661160011, 5169.844904652867, 5251.186066159751, 4920.114379411741, 5001.049800537886, 5030.899382012412, 5049.7727602257255, 5050.621203409685, 4944.604431645388, 5027.698338099725, 5031.4213865405945, 5031.471695471922, 5033.798648054037, 4955.182394904945, 4962.652614317108, 4977.104121258209, 4989.301497790239, 5001.899962000667, 5041.495850414929, 5047.545720885138, 5073.594112471067, 5074.213496907985, 5077.213031980049, 4980.05378977993, 4993.076280888047, 5025.246213068069, 5040.269160971712, 5051.040302342543], "value": 4727.290082114938, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.5593701290916, 552.9861753456162, 556.5630979680951, 563.509746520527, 590.2091197794954, 35099.82450087749, 39020.219595608105, 39023.414648780294, 39057.804710976474, 39123.80438097812, 39756.0, 40233.396499052535, 40299.0, 40362.51480901993, 40391.19217615646, 7466.066741657293, 7613.352865006481, 7681.846363072736, 7768.067831860185, 7792.571408572526, 10172.084512393883, 10468.371897686164, 10476.0, 10477.68566942993, 10498.160147188206, 9707.369021013646, 9733.17268032218, 9745.122938935494, 9746.610135594568, 9751.804963900719, 7595.734149304803, 7755.534667919943, 8497.66009359628, 8600.698975535857, 9805.411675299505, 9631.566579503939, 9928.255380846465, 9930.106290433823, 9932.255080868963, 9951.303408761389, 38465.61534384648, 39063.609363906566, 39070.60929390726, 39139.8043009786, 39147.21705565901, 3622.873199438033, 3641.8725344613076, 3674.7427680062406, 3718.1262403335195, 3735.1595890924323, 27181.0, 27567.0, 28869.71130288712, 29853.850730746424, 30606.081817545288, 3407.3015031918644, 3525.4711793230963, 3534.240138370237, 3566.8038257895805, 3583.372909740785, 263651.0, 269882.0, 271139.0, 385249.0, 387422.0, 53216.46783532193, 53786.0, 53877.73061134748, 53905.46094539083, 53916.46083539193, 57273.0, 57411.665900138374, 57490.71254643783, 57555.42444575501, 57570.0, 12318.137730358969, 12491.188072775241, 12492.312922789333, 12672.049596280316, 12682.0, 7954.761357159295, 8170.346372290202, 8888.822223555493, 9029.36794424384, 9402.717918462457, 18800.905995469937, 18841.717374239397, 18935.621287574173, 18946.52633684169, 19087.0, 6803.414702413151, 6939.738322988187, 6978.441724662015, 7091.184513780878, 7110.644467776694, 813.808754942588, 815.176671561727, 824.7855557555079, 831.9916800832035, 848.5714714069479, 27100.86449567779, 27633.723662763114, 27941.301467463476, 28227.15318540447, 28231.0, 949.392388871123, 955.8996305387834, 959.1415682963708, 968.8062387522524, 1055.266589720137, 12723.172993755376, 13500.797488037688, 13748.312584370742, 13791.379387927567, 13795.931020345035, 342635.0, 344941.0, 347591.0, 347593.0, 350067.0, 50996.23505647418, 51563.22655160176, 51592.22611660828, 51602.48397516052, 51778.22332665013, 1112.6328311657135, 1175.7883580955338, 1207.4385410784027, 1223.7124275795181, 1223.8225457308818, 24210.0, 24812.25563233106, 25684.743152568983, 25774.613380798928, 25881.09416170425, 40813.38779918363, 42369.788151059045, 42486.0, 42537.0, 42556.78721606372, 319418.0, 319976.0, 320013.0, 322313.0, 322503.0, 22763.772362276166, 23602.881985589964, 24838.37904052413, 25874.482510350063, 26040.0, 40881.795591023045, 40994.0, 41594.376084358155, 41642.167156657306, 42207.155856883306, 34604.65395346014, 35499.46750798688, 35674.46488302729, 35811.28377432489, 35966.82016590005, 35055.09228707932, 35218.47172292469, 35307.0, 35308.82345588256, 35314.82342588271, 33843.0, 34036.659633403346, 34081.0, 34899.12752181114, 34902.0, 28913.42637175115, 30939.226519337197, 31150.22124446907, 31180.53229201609, 31766.52350214795, 23675.64486532738, 23689.503613021916, 23794.40513987164, 24238.636420453353, 24244.272671819872], "value": 17681.281169071677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.4736066940594, 355.16890476285505, 370.9091272638203, 373.4659437005083, 386.78340129527464, 13346.866531334685, 15736.21318934053, 15930.601734956632, 16418.671626567462, 17337.39319123831, 16817.15914204291, 16871.0, 16876.071816050113, 17026.574335641617, 17028.659426811457, 4964.354633897588, 4967.453580106186, 5115.974420127913, 5148.150555158413, 5153.510416510426, 3904.1606054698236, 4100.774457404841, 5034.773435195425, 5129.2562578426105, 5706.629069110494, 5982.282126144869, 6075.939240607581, 6084.117802918574, 6089.05619628956, 6094.177286066369, 5884.352721200665, 5999.790007349766, 6039.63762174271, 6040.305364883051, 6077.179580756608, 5297.602679799031, 5331.413544510101, 5335.733213339317, 5339.946600534023, 5341.145416733304, 19431.417057488157, 19830.50423739418, 19861.304854330174, 20026.0, 20084.899575502175, 2055.7738648748627, 2109.5991761565406, 2123.6495978163584, 2151.053536443975, 2197.505561248722, 12222.938885305606, 12914.41885115172, 13291.933540332333, 13305.600831975056, 13549.119307244997, 1672.180631490573, 1721.3544920654754, 1745.23209787693, 1776.564741638294, 1778.262021261175, 57593.712031439994, 58968.705156474374, 59063.0, 59422.40577594212, 60663.0, 9491.952540237393, 9721.0, 9785.524760536196, 9873.654422095193, 9927.751806204902, 13221.206727596182, 13240.801387979189, 13251.271180085188, 13253.0, 13270.535531256553, 6135.447809697149, 6292.0, 6451.258105317855, 6476.093346931433, 6515.4787616990525, 5654.208410822488, 5683.914741278884, 5816.389279125631, 5817.7091145442555, 5833.416658334218, 6079.270487541435, 6194.721237544321, 6203.875922481616, 6213.689315534205, 6250.967970783563, 1781.1094452773614, 1798.379559052125, 1804.9909750451163, 1807.268056437145, 1816.9364072257408, 752.4695089961564, 788.5702292250622, 790.1584812175015, 802.7912742686826, 804.7062822070019, 15823.841761582467, 16383.180840957904, 16464.588385290223, 16505.422310219325, 17143.57141071483, 779.8401327727802, 785.7014334552833, 793.4049462902823, 801.8556659801175, 806.2944923192207, 9172.678956236634, 9724.75688107803, 9781.16860066888, 9852.261080418997, 9932.155766759906, 341109.0, 347381.0, 347513.0, 347613.0, 348593.0, 46104.0, 47442.0, 61307.693461532406, 61598.38401616016, 63771.36228637654, 896.6771962093641, 901.7384958362002, 923.2890674180843, 930.1303281431883, 932.305432452827, 14338.426462941783, 14392.136471811726, 14726.63183420423, 14839.183844888636, 15326.310316035582, 31530.36939261248, 31855.522167167048, 32034.67965320317, 33295.83352083224, 33299.0, 41260.0, 41349.1730165401, 41446.58553414427, 43022.569774303105, 44654.553454465036, 7614.771556853303, 7735.497192682457, 7961.761147165363, 7985.8402831944195, 8068.9596552016865, 12085.75828483443, 12152.75694486123, 12261.203021803554, 12278.877211227773, 12661.683457913627, 9955.352902061633, 10290.228232882415, 10444.112250458797, 10448.373097614167, 10821.783564328827, 10397.428141452292, 10438.164946804389, 10652.467376631292, 10800.459977001276, 10805.18961077938, 10016.649417270652, 10243.0, 10332.0, 10422.322549034288, 10680.145588352758, 7716.92283077162, 7738.535687858746, 7757.7672669819995, 7952.681892724227, 7984.121746607984, 10334.638287659896, 10402.531886065297, 10503.842442363219, 10584.153267738717, 10623.0], "value": 8074.6199119382745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.4169552853448, 1297.7469393468273, 1297.7664020476311, 1302.7915533514633, 1311.1149973767704, 6969.6166710830885, 7398.519096258739, 7401.555906645592, 7410.5924174170395, 7423.480356375056, 6513.511486638497, 6732.326767323265, 7137.85724285514, 7251.854962900738, 7396.186419493852, 2130.1692339987417, 2170.696102545645, 2171.055590817999, 2172.0443005077796, 2175.2386664667397, 2138.818200452956, 2171.6851056596784, 2176.858504197222, 2179.3135162423864, 2182.2362173239317, 1828.3509354179275, 1927.3447028010473, 2075.107703687414, 2142.5393540388814, 2154.375231182955, 2117.6400011998, 2123.055240418015, 2124.511362386644, 2125.1499400240027, 2126.542793299448, 2102.6845973104005, 2113.482196861779, 2122.0, 2125.3517677108475, 2128.2231985325398, 7357.0803649543805, 7408.703651853919, 7410.851782964364, 7443.106827180719, 7446.180920098785, 4308.676849236288, 4437.822487100512, 4438.644908407319, 4439.866803995853, 4440.888977775549, 2869.4834929712642, 3027.409655117253, 3085.1361618746564, 3087.8076565638426, 3108.626964764243, 1144.0870501016382, 1179.787638225119, 1211.1521934645784, 1215.4591206912928, 1231.993840030803, 9701.6604418845, 9715.36850104741, 9719.514024298827, 9722.756931076708, 9724.513774311326, 4129.359949207877, 4198.6011328923705, 4262.168877068942, 4268.146370725867, 4290.163418133434, 7829.804254893673, 7900.920990790133, 7902.683892644345, 7903.68385264583, 7910.485818421784, 4372.934405983913, 4375.584319489643, 4376.540463251376, 4377.737335759864, 4378.124375124988, 3635.5819080806045, 3738.626137386294, 3942.5071866016747, 4048.3725022621543, 4048.4939382577177, 3575.3743094958277, 3609.8375573099274, 3706.3314413622147, 3722.813859307024, 3761.1349389640536, 1217.1479964025218, 1225.154643296123, 1236.361820587707, 1247.538410787996, 1250.1374051904156, 766.7929658992041, 798.269583331257, 803.4857691077715, 809.720646376999, 816.2327412232482, 4489.820407183676, 4623.468301145412, 4673.953260467351, 4690.788914498856, 4897.4857639947295, 495.247224219192, 508.54739282039014, 512.2418820146205, 513.6635503745053, 518.8728761453392, 3091.180837078176, 3151.921201969969, 3206.246532064963, 3229.370272796782, 3320.5019247113128, 8267.131951145044, 8727.60725767342, 8780.165884240987, 8798.780030499289, 8808.603612837438, 8779.561021948877, 8785.428947118417, 8793.340499462438, 8793.472391656522, 8796.560171991376, 6686.7993960181275, 6935.618540980295, 6982.96508517444, 6996.6151861648095, 6999.12510936133, 4994.275830004556, 5003.374578177728, 5014.5283527544425, 5026.070177017264, 5244.160934250502, 7488.213737557478, 7751.534907905544, 8070.031596208492, 8094.757157285291, 8097.39269554774, 7736.535807851547, 7800.531968081933, 7950.165232650488, 7950.920490795249, 8209.179082091743, 5819.098039803837, 5988.401159884151, 6074.119252708244, 6396.48828093742, 6523.3150519194805, 6715.127033485811, 6893.310668933267, 6925.480588955949, 6932.23745388017, 6932.514723969273, 6358.2687990879795, 6454.774082907264, 6509.641969691713, 6532.869342613026, 6656.633885136364, 6369.897933681967, 6387.393197646216, 6464.612123272619, 6528.347165283433, 6546.869062618625, 5632.295963004624, 5698.1452782083015, 5699.800506982236, 5704.743286552198, 5705.258316418839, 4136.937945930753, 4205.5794420557695, 4304.0, 4306.138772245501, 4356.237658409829, 3935.4883865097354, 4096.487939007624, 4097.221527909687, 4210.59999300066, 4296.849610263625], "value": 3877.47432917262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.18662263416775, 359.59186323522795, 360.4503132722598, 365.8591442294717, 366.0482744863356, 4763.618910487161, 4778.259369797678, 4782.593479554233, 4792.0, 4850.733209673466, 4771.689840160388, 4777.80888764449, 4790.496997815231, 4791.544803243695, 4805.231163013918, 3704.444333349992, 3733.4026555751084, 3736.570294416136, 3802.90492737681, 3906.472626195463, 3787.9810600947094, 3804.6195380461872, 3804.7907365094907, 3804.8668296609626, 3809.847606095753, 3778.3576791945297, 3780.773153610792, 3780.9054773630605, 3784.8107594620296, 3790.128270497787, 3599.874004409833, 3763.303788799081, 3783.0, 3783.829727662262, 3786.0724122590004, 3292.1275861896615, 3300.488424294238, 3315.8673653053847, 3325.2850637112897, 3332.2669012817382, 4677.929831052537, 4683.953160468386, 4691.906161876744, 4698.718076915396, 4705.4588722296685, 4269.359596060584, 4277.123189746126, 4284.400183974246, 4299.376590394375, 4304.289792184286, 4908.502906613481, 4966.106100901836, 4992.176290911995, 5013.4485206627605, 5022.698638081691, 3621.112827357288, 3625.909352266188, 3627.2564124354444, 3633.3823250047553, 3642.1805093853936, 5031.874203144951, 5247.47525247476, 5249.4750524947585, 5266.8419947401635, 5271.0, 3563.7861728295866, 3575.3028159508644, 3659.2864391443945, 3671.7429779915683, 3685.0, 3663.578688450862, 3691.520102386726, 3691.9356933744925, 3702.7222958278235, 3709.5718148513015, 4181.790910454465, 4213.747175169499, 4253.80857861397, 4286.357046443059, 4288.7855607220135, 4844.176489996709, 4915.749269826976, 4973.378327709036, 5022.372203474566, 5046.1926091825135, 1227.5151315230435, 1229.8155276708387, 1236.9319687417278, 1241.2118304876287, 1246.2647038247478, 841.8400503904238, 864.9394542382099, 874.1215078845719, 879.3185281406961, 889.0709208876739, 4258.510271318776, 4278.272693642089, 4296.183725092223, 4296.484421869333, 4317.5967810461725, 5004.474530174352, 5006.524380183877, 5006.64953453262, 5009.323741294951, 5028.647994640413, 4249.171411574714, 4261.0, 4262.786860657016, 4286.87139385819, 4296.3555466680245, 2515.6729625148614, 2661.920142395731, 2663.6670416197976, 2664.6935602405583, 2686.100156447612, 4632.652551058684, 4649.349091127245, 4652.697574657636, 4657.534246575316, 4662.370579971728, 4953.975230123826, 5059.848204553869, 5088.262201980692, 5095.8980820383385, 5164.612654050961, 4547.386102876069, 4570.611498022706, 4622.9829437523695, 4632.5830675239395, 4645.5354464553275, 4979.651424400257, 4980.452150263541, 4980.477049909708, 4989.725565093955, 4992.700437973734, 4911.533404326583, 4927.408710954708, 4947.921872813355, 4982.576480999156, 5001.624878134082, 4530.682852200446, 4634.83778067766, 4791.448983366958, 4832.855014349575, 4836.250381190921, 3528.188516641238, 3552.2540266544315, 3659.853605855736, 3663.775877228022, 3668.5414323209598, 5178.533931946143, 5178.689278643143, 5191.636585438983, 5192.922106168335, 5205.167173252262, 4915.493098519709, 5054.2671312660195, 5058.974705126451, 5062.2406639002975, 5064.8987020258655, 4964.379452568429, 4987.850364489072, 4996.3504744383, 5000.924986125284, 5009.348784657971, 4900.852974410774, 4903.224517121867, 4924.778384972757, 4954.925676114789, 4969.1800852860315, 4803.375561177025, 4998.925016124834, 5002.924956125588, 5006.745615350052, 5084.9745751271, 4869.780859861244, 4870.488598697228, 4875.68308059975, 4884.926726099183, 4907.0], "value": 3769.7777231976424, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.605940524793, 697.1007400453416, 736.9042024536809, 753.7248904149984, 775.1628241499179, 13254.867451325485, 14379.35292911819, 14420.855791442082, 14428.639284017905, 14547.127172369652, 12144.3320617366, 12175.939120304409, 12177.025837932966, 12185.878141218585, 12196.878031219707, 636.150738763751, 670.3598063849037, 775.7982924439658, 802.3821657323842, 819.2299238715618, 815.5066184958104, 816.7998840284139, 824.8803923431099, 825.8389614025267, 826.0954255090666, 866.4194989357114, 867.7266661001796, 870.5864714260707, 870.9042005379404, 871.3116637856086, 674.7806962737081, 710.8045287545915, 815.0463957170066, 859.325429537813, 860.7417774667574, 627.3506920337429, 627.3788948940562, 629.2857606616477, 634.1344065350763, 634.3624657219511, 13969.3713782879, 13979.301034948314, 14002.789958150635, 14004.929975350058, 14008.369623366973, 11860.64268853218, 12051.216670916363, 12232.877671223263, 12578.874211257953, 12604.0, 12109.0, 12244.142909996304, 12357.045745361487, 12619.0, 12814.16707913992, 4158.209940111369, 4207.305794543897, 4254.446921900164, 4365.43249377582, 4379.08039311745, 40756.59243407587, 64587.3541264586, 64783.0, 65484.67257663729, 66251.33748662547, 9928.751781205383, 9940.652077177409, 10044.598216071421, 10093.385058390622, 10270.281080324456, 17584.56038599045, 17696.55758606045, 17723.379681711336, 17741.822581774017, 18923.43229703111, 6078.635281883101, 6120.908186377208, 6129.233845769279, 6159.014557670751, 6173.81478555644, 3268.722158616544, 3304.3556506481004, 3329.467285234351, 3353.329334133134, 3423.9657603424143, 2882.4523340565643, 2894.956575651367, 2943.7644988400875, 2948.469275530425, 2961.1116665000413, 491.1674711364203, 496.27543786072016, 503.3984388655521, 505.7926250237385, 507.7029937486612, 410.93928273314043, 427.6258274010241, 435.0667817531378, 454.43422938441194, 458.85546052993203, 6920.204176519664, 7021.122359705037, 7038.155421349471, 7148.428125749928, 7316.780496585111, 429.49534297200665, 435.3100335967527, 454.63856234293814, 466.09344824317054, 472.4897111119982, 3424.5548078749603, 3475.357058944053, 3480.9825950870086, 3559.94660080099, 3617.312710585033, 67754.32245677579, 68781.96827047598, 68957.31042689609, 70395.0, 70581.0, 60342.69828650829, 60807.0, 61085.38914610886, 61477.38522614806, 61897.69051154715, 44444.55555444534, 53374.73312633412, 53455.46544534505, 53668.463315367124, 56676.43323566794, 8196.713189355785, 9085.863712044193, 9108.590113445043, 9159.633614655339, 9351.719448416557, 29598.0, 34324.48513272353, 35308.0, 35820.462693060144, 37390.25219495541, 33066.17334566655, 34327.31345373028, 38552.42171367488, 47602.76198618985, 47697.76151119222, 10440.164786817188, 10446.477676116012, 10654.0, 10657.893421066, 10752.623658171926, 10840.728981775519, 11116.498787772687, 11313.151513636345, 11409.885901140882, 11547.769044618892, 11257.49341279628, 11457.0, 11462.0, 11712.355820429959, 12237.20458170216, 10791.56833726642, 11179.55281788752, 11284.717882053013, 11340.432978351215, 11405.714857128638, 11242.55029798799, 11260.88739112631, 11872.643820685393, 12206.389680515762, 12206.87793122093, 9177.0, 9200.585973631336, 9427.717168484956, 9742.512874356396, 9801.460919649488, 9902.554385052834, 10069.69790906274, 10075.596976120873, 10088.192944564564, 10128.44293563861], "value": 6137.811761589195, "unit": "score"}], "name": "dromaeo_css", "value": 6266.5682847624685, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 125.0, 123.0, 164.0, 125.0, 127.0, 125.0, 130.0, 153.0, 126.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 163.0, 161.0, 163.0, 162.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 104.0, 76.0, 76.0, 76.0, 77.0, 93.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 108.0, 84.0, 85.0, 85.0, 100.0, 85.0, 85.0, 106.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 119.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 148.0, 148.0, 149.0, 150.0, 149.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 63.0, 63.0, 62.0, 64.0, 62.0, 73.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 77.0, 67.0, 67.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 86.0, 85.0, 87.0, 91.0, 87.0, 83.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 145.0, 138.0, 134.0, 132.0, 133.0, 133.0, 135.0, 177.0, 134.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 179.0, 184.0, 180.0, 178.0, 180.0, 180.0, 179.0, 180.0, 178.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1550.8999999999999, "alertThreshold": 2.0}]} 08:09:41 INFO - Return code: 0 08:09:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:41 INFO - # TBPL SUCCESS # 08:09:41 INFO - Running post-action listener: _resource_record_post_action 08:09:41 INFO - [mozharness: 2016-12-26 16:09:41.365289Z] Finished run-tests step (success) 08:09:41 INFO - Running post-run listener: _resource_record_post_run 08:09:41 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 20480; Write bytes: 394817536; Read time: 160; Write time: 281232 08:09:41 INFO - TinderboxPrint: CPU usage
13.1% 08:09:41 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 160 08:09:41 INFO - TinderboxPrint: I/O write bytes / time
394,817,536 / 281,232 08:09:41 INFO - TinderboxPrint: CPU idle
8,330.8 (86.9%) 08:09:41 INFO - TinderboxPrint: CPU user
1,212.5 (12.6%) 08:09:41 INFO - TinderboxPrint: Swap in / out
0 / 0 08:09:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110968832; Read time: 0; Write time: 64440 08:09:41 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 16384; Write bytes: 283848704; Read time: 152; Write time: 216792 08:09:42 INFO - Running post-run listener: _upload_blobber_files 08:09:42 INFO - Blob upload gear active. 08:09:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:42 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 08:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:09:42 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:43 INFO - (blobuploader) - INFO - Done attempting. 08:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:46 INFO - (blobuploader) - INFO - Done attempting. 08:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:47 INFO - (blobuploader) - INFO - Done attempting. 08:09:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:09:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:09:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:48 INFO - (blobuploader) - INFO - Done attempting. 08:09:48 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:48 INFO - Return code: 0 08:09:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/70d1218680c94bcaf8fa082b5ff21c06c24d31a2fa1fbe6f85bc5badbd3554911f8a10dbd07a325bbd223a1403c2e2769e923b86cf244c259ce7e2acef280054", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b1702e9847105e998084a8de4ed4f743cdb628ff5215c2046df404ea854768ce08ec8cff3b46e3f85bbb2052496534a9f8a655ad4961cfd79f0322b3b5e9c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d59d22876adfd0da97c711a455692e8a1b1353315941a6b4ef2e3fd76c97e08aecc4f9b5ff62fa4d64fe035e83aa6beb1e15b48e7aec35b6cd12330ddd60624a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ec0eada715cef4e0883109b6e691b4b2ab72fadc2ea8510f1c88b46ba2df35592ace09e2f2c86f23207311dd9125623339626c184792fa2c6c11441db34782"} 08:09:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:48 INFO - Contents: 08:09:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/70d1218680c94bcaf8fa082b5ff21c06c24d31a2fa1fbe6f85bc5badbd3554911f8a10dbd07a325bbd223a1403c2e2769e923b86cf244c259ce7e2acef280054", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b1702e9847105e998084a8de4ed4f743cdb628ff5215c2046df404ea854768ce08ec8cff3b46e3f85bbb2052496534a9f8a655ad4961cfd79f0322b3b5e9c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d59d22876adfd0da97c711a455692e8a1b1353315941a6b4ef2e3fd76c97e08aecc4f9b5ff62fa4d64fe035e83aa6beb1e15b48e7aec35b6cd12330ddd60624a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ec0eada715cef4e0883109b6e691b4b2ab72fadc2ea8510f1c88b46ba2df35592ace09e2f2c86f23207311dd9125623339626c184792fa2c6c11441db34782"} 08:09:48 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:48 INFO - Copying logs to upload dir... 08:09:48 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.050874 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-26 08:09:47.335811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:47.339060) ========= 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=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/70d1218680c94bcaf8fa082b5ff21c06c24d31a2fa1fbe6f85bc5badbd3554911f8a10dbd07a325bbd223a1403c2e2769e923b86cf244c259ce7e2acef280054", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b1702e9847105e998084a8de4ed4f743cdb628ff5215c2046df404ea854768ce08ec8cff3b46e3f85bbb2052496534a9f8a655ad4961cfd79f0322b3b5e9c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d59d22876adfd0da97c711a455692e8a1b1353315941a6b4ef2e3fd76c97e08aecc4f9b5ff62fa4d64fe035e83aa6beb1e15b48e7aec35b6cd12330ddd60624a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ec0eada715cef4e0883109b6e691b4b2ab72fadc2ea8510f1c88b46ba2df35592ace09e2f2c86f23207311dd9125623339626c184792fa2c6c11441db34782"} build_url:https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/Wh6tnY0XThOTOOIQ3uAfmQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/70d1218680c94bcaf8fa082b5ff21c06c24d31a2fa1fbe6f85bc5badbd3554911f8a10dbd07a325bbd223a1403c2e2769e923b86cf244c259ce7e2acef280054", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b1702e9847105e998084a8de4ed4f743cdb628ff5215c2046df404ea854768ce08ec8cff3b46e3f85bbb2052496534a9f8a655ad4961cfd79f0322b3b5e9c40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d59d22876adfd0da97c711a455692e8a1b1353315941a6b4ef2e3fd76c97e08aecc4f9b5ff62fa4d64fe035e83aa6beb1e15b48e7aec35b6cd12330ddd60624a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ec0eada715cef4e0883109b6e691b4b2ab72fadc2ea8510f1c88b46ba2df35592ace09e2f2c86f23207311dd9125623339626c184792fa2c6c11441db34782"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:47.364443) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:47.364813) ========= 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=923d835db2ba3686ebe126b700000192-1482764418.188136-1693010808 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 08:09:47.414448) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:09:47.414819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 08:09:47.415208) ========= ========= Total master_lag: 0.57 =========