builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1478690767.47 results: success (0) buildid: 20161108173621 builduid: 083c302c289d4fffa60edb78cf695dbd revision: ae3b6bfb810476141647ad681b796ed118062412 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.473322) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.473790) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.474107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.533041) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.533342) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-09 03:26:07-- 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% 33.2M=0s 2016-11-09 03:26:07 (33.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088445 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.635215) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.635531) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022140 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.685144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:07.685522) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ae3b6bfb810476141647ad681b796ed118062412 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ae3b6bfb810476141647ad681b796ed118062412 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-09 03:26:07,921 truncating revision to first 12 chars 2016-11-09 03:26:07,922 Setting DEBUG logging. 2016-11-09 03:26:07,922 attempt 1/10 2016-11-09 03:26:07,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ae3b6bfb8104?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-09 03:26:08,455 unpacking tar archive at: mozilla-aurora-ae3b6bfb8104/testing/mozharness/ program finished with exit code 0 elapsedTime=0.874072 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:08.574615) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:08.575094) ========= script_repo_revision: ae3b6bfb810476141647ad681b796ed118062412 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:08.575702) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:08.576130) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-09 03:26:08.587874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-09 03:26:08.588490) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:26:08 INFO - MultiFileLogger online at 20161109 03:26:08 in /builds/slave/test 03:26:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:26:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:26:08 INFO - {'append_to_log': False, 03:26:08 INFO - 'base_work_dir': '/builds/slave/test', 03:26:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:26:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:26:08 INFO - 'branch': 'Mozilla-Aurora', 03:26:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:26:08 INFO - 'config_files': ('talos/linux_config.py',), 03:26:08 INFO - 'default_actions': ('clobber', 03:26:08 INFO - 'read-buildbot-config', 03:26:08 INFO - 'download-and-extract', 03:26:08 INFO - 'populate-webroot', 03:26:08 INFO - 'create-virtualenv', 03:26:08 INFO - 'install', 03:26:08 INFO - 'run-tests'), 03:26:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:26:08 INFO - 'download_minidump_stackwalk': True, 03:26:08 INFO - 'download_symbols': 'ondemand', 03:26:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:26:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:26:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:26:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:26:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:26:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:26:08 INFO - 'installer_path': 'installer.exe', 03:26:08 INFO - 'log_level': 'info', 03:26:08 INFO - 'log_name': 'talos', 03:26:08 INFO - 'log_to_console': True, 03:26:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:26:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:26:08 INFO - 'opt_config_files': (), 03:26:08 INFO - 'pip_index': False, 03:26:08 INFO - 'sps_profile': False, 03:26:08 INFO - 'sps_profile_interval': 0, 03:26:08 INFO - 'suite': 'dromaeojs', 03:26:08 INFO - 'system_bits': '32', 03:26:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:26:08 INFO - 'title': 'talos-linux64-ix-047', 03:26:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:26:08 INFO - 'use_talos_json': True, 03:26:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:26:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:26:08 INFO - 'work_dir': 'build'} 03:26:08 INFO - [mozharness: 2016-11-09 11:26:08.920328Z] Running clobber step. 03:26:08 INFO - Running pre-action listener: _resource_record_pre_action 03:26:08 INFO - Running main action method: clobber 03:26:08 INFO - rmtree: /builds/slave/test/build 03:26:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:26:09 INFO - Running post-action listener: _resource_record_post_action 03:26:09 INFO - [mozharness: 2016-11-09 11:26:09.378177Z] Finished clobber step (success) 03:26:09 INFO - [mozharness: 2016-11-09 11:26:09.378278Z] Running read-buildbot-config step. 03:26:09 INFO - Running pre-action listener: _resource_record_pre_action 03:26:09 INFO - Running main action method: read_buildbot_config 03:26:09 INFO - Using buildbot properties: 03:26:09 INFO - { 03:26:09 INFO - "project": "", 03:26:09 INFO - "product": "firefox", 03:26:09 INFO - "script_repo_revision": "production", 03:26:09 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:26:09 INFO - "repository": "", 03:26:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:26:09 INFO - "buildid": "20161108173621", 03:26:09 INFO - "pgo_build": "True", 03:26:09 INFO - "basedir": "/builds/slave/test", 03:26:09 INFO - "buildnumber": 872, 03:26:09 INFO - "slavename": "talos-linux64-ix-047", 03:26:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:26:09 INFO - "platform": "ubuntu64_hw", 03:26:09 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:26:09 INFO - "branch": "mozilla-aurora", 03:26:09 INFO - "repo_path": "releases/mozilla-aurora", 03:26:09 INFO - "revision": "ae3b6bfb810476141647ad681b796ed118062412", 03:26:09 INFO - "stage_platform": "linux64-pgo", 03:26:09 INFO - "builduid": "083c302c289d4fffa60edb78cf695dbd", 03:26:09 INFO - "slavebuilddir": "test" 03:26:09 INFO - } 03:26:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:26:09 INFO - Running post-action listener: _resource_record_post_action 03:26:09 INFO - [mozharness: 2016-11-09 11:26:09.380701Z] Finished read-buildbot-config step (success) 03:26:09 INFO - [mozharness: 2016-11-09 11:26:09.380790Z] Running download-and-extract step. 03:26:09 INFO - Running pre-action listener: _resource_record_pre_action 03:26:09 INFO - Running main action method: download_and_extract 03:26:09 INFO - mkdir: /builds/slave/test/build/tests 03:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:09 INFO - trying https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:09 INFO - Downloading https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:26:09 INFO - Downloaded 1361 bytes. 03:26:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:26:09 INFO - Using the following test package requirements: 03:26:09 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:26:09 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:26:09 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:26:09 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'jsshell-linux-x86_64.zip'], 03:26:09 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:26:09 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:26:09 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:26:09 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:26:09 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:26:09 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:26:09 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:26:09 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:26:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:26:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:26:09 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:26:10 INFO - Expected file size: 21895944 03:26:14 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:26:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:26:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:26:14 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:26:15 INFO - Expected file size: 21895944 03:26:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:26:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:26:20 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:26:20 INFO - Expected file size: 14414089 03:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:21 INFO - trying https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:21 INFO - Downloading https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:26:22 INFO - Downloaded 63984307 bytes. 03:26:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:22 INFO - mkdir: /builds/slave/test/properties 03:26:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:26:22 INFO - Writing to file /builds/slave/test/properties/build_url 03:26:22 INFO - Contents: 03:26:22 INFO - build_url:https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:26:23 INFO - Running post-action listener: _resource_record_post_action 03:26:23 INFO - Running post-action listener: set_extra_try_arguments 03:26:23 INFO - [mozharness: 2016-11-09 11:26:23.059028Z] Finished download-and-extract step (success) 03:26:23 INFO - [mozharness: 2016-11-09 11:26:23.059256Z] Running populate-webroot step. 03:26:23 INFO - Running pre-action listener: _resource_record_pre_action 03:26:23 INFO - Running main action method: populate_webroot 03:26:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:26:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:26:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:26:23 INFO - '--tppagecycles', 03:26:23 INFO - '7'], 03:26:23 INFO - 'tests': ['tsvgm']}}, 03:26:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:26:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:26:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:26:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:26:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:26:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['damp', 'tps']}, 03:26:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'tests': ['damp', 'tps']}, 03:26:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['dromaeo_dom']}, 03:26:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:26:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['basic_compositor_video']}, 03:26:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:26:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'other-e10s': {'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['a11yr', 03:26:23 INFO - 'ts_paint', 03:26:23 INFO - 'tpaint', 03:26:23 INFO - 'sessionrestore', 03:26:23 INFO - 'sessionrestore_no_auto_restore', 03:26:23 INFO - 'tabpaint']}, 03:26:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['tsvgx', 03:26:23 INFO - 'tsvgr_opacity', 03:26:23 INFO - 'tart', 03:26:23 INFO - 'tscrollx', 03:26:23 INFO - 'cart']}, 03:26:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:26:23 INFO - 'tsvgr_opacity', 03:26:23 INFO - 'tart', 03:26:23 INFO - 'tscrollx', 03:26:23 INFO - 'cart']}, 03:26:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'talos_options': ['--disable-e10s'], 03:26:23 INFO - 'tests': ['tp5o']}, 03:26:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'tests': ['tp5o']}, 03:26:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'talos_options': ['--disable-e10s', 03:26:23 INFO - '--xperf_path', 03:26:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:23 INFO - 'tests': ['tp5n']}, 03:26:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:26:23 INFO - 'talos_options': ['--xperf_path', 03:26:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:26:23 INFO - 'tests': ['tp5n']}}, 03:26:23 INFO - 'talos.zip': {'path': '', 03:26:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:26:23 INFO - Running post-action listener: _resource_record_post_action 03:26:23 INFO - [mozharness: 2016-11-09 11:26:23.076263Z] Finished populate-webroot step (success) 03:26:23 INFO - [mozharness: 2016-11-09 11:26:23.076351Z] Running create-virtualenv step. 03:26:23 INFO - Running pre-action listener: _resource_record_pre_action 03:26:23 INFO - Running main action method: create_virtualenv 03:26:23 INFO - Creating virtualenv /builds/slave/test/build/venv 03:26:23 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 03:26:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:26:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:26:23 INFO - Using real prefix '/usr' 03:26:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:26:23 INFO - Installing distribute.............................................................................................................................................................................................done. 03:26:25 INFO - Installing pip.................done. 03:26:25 INFO - Return code: 0 03:26:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:25 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:25 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 03:26:25 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 03:26:25 INFO - Using env: {'DISPLAY': ':0', 03:26:25 INFO - 'HOME': '/home/cltbld', 03:26:25 INFO - 'LANG': 'en_US.UTF-8', 03:26:25 INFO - 'LANGUAGE': 'en_US:en', 03:26:25 INFO - 'LOGNAME': 'cltbld', 03:26:25 INFO - 'MAIL': '/var/mail/cltbld', 03:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:25 INFO - 'MOZ_NO_REMOTE': '1', 03:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:25 INFO - 'NO_EM_RESTART': '1', 03:26:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:25 INFO - 'PWD': '/builds/slave/test', 03:26:25 INFO - 'SHELL': '/bin/bash', 03:26:25 INFO - 'SHLVL': '1', 03:26:25 INFO - 'TERM': 'linux', 03:26:25 INFO - 'TMOUT': '86400', 03:26:25 INFO - 'USER': 'cltbld', 03:26:25 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:25 INFO - '_': '/tools/buildbot/bin/python'} 03:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:25 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 03:26:25 INFO - Cleaning up... 03:26:25 INFO - Return code: 0 03:26:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:25 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:25 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 03:26:25 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 03:26:25 INFO - Using env: {'DISPLAY': ':0', 03:26:25 INFO - 'HOME': '/home/cltbld', 03:26:25 INFO - 'LANG': 'en_US.UTF-8', 03:26:25 INFO - 'LANGUAGE': 'en_US:en', 03:26:25 INFO - 'LOGNAME': 'cltbld', 03:26:25 INFO - 'MAIL': '/var/mail/cltbld', 03:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:25 INFO - 'MOZ_NO_REMOTE': '1', 03:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:25 INFO - 'NO_EM_RESTART': '1', 03:26:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:25 INFO - 'PWD': '/builds/slave/test', 03:26:25 INFO - 'SHELL': '/bin/bash', 03:26:25 INFO - 'SHLVL': '1', 03:26:25 INFO - 'TERM': 'linux', 03:26:25 INFO - 'TMOUT': '86400', 03:26:25 INFO - 'USER': 'cltbld', 03:26:25 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:25 INFO - '_': '/tools/buildbot/bin/python'} 03:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:26 INFO - Downloading/unpacking psutil>=3.1.1 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:26:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:28 INFO - Installing collected packages: psutil 03:26:28 INFO - Running setup.py install for psutil 03:26:28 INFO - building 'psutil._psutil_linux' extension 03:26:28 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 03:26:28 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 03:26:28 INFO - building 'psutil._psutil_posix' extension 03:26:28 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 03:26:28 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 03:26:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:26:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:26:28 INFO - Successfully installed psutil 03:26:28 INFO - Cleaning up... 03:26:28 INFO - Return code: 0 03:26:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:26: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')]} 03:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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')]} 03:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:26: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 mozsystemmonitor==0.3 03:26:28 INFO - Using env: {'DISPLAY': ':0', 03:26:28 INFO - 'HOME': '/home/cltbld', 03:26:28 INFO - 'LANG': 'en_US.UTF-8', 03:26:28 INFO - 'LANGUAGE': 'en_US:en', 03:26:28 INFO - 'LOGNAME': 'cltbld', 03:26:28 INFO - 'MAIL': '/var/mail/cltbld', 03:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:28 INFO - 'MOZ_NO_REMOTE': '1', 03:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:28 INFO - 'NO_EM_RESTART': '1', 03:26:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:28 INFO - 'PWD': '/builds/slave/test', 03:26:28 INFO - 'SHELL': '/bin/bash', 03:26:28 INFO - 'SHLVL': '1', 03:26:28 INFO - 'TERM': 'linux', 03:26:28 INFO - 'TMOUT': '86400', 03:26:28 INFO - 'USER': 'cltbld', 03:26:28 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:28 INFO - '_': '/tools/buildbot/bin/python'} 03:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:26:30 INFO - Installing collected packages: mozsystemmonitor 03:26:30 INFO - Running setup.py install for mozsystemmonitor 03:26:31 INFO - Successfully installed mozsystemmonitor 03:26:31 INFO - Cleaning up... 03:26:31 INFO - Return code: 0 03:26:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:26:31 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')]} 03:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:31 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')]} 03:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:31 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:31 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 03:26:31 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 03:26:31 INFO - Using env: {'DISPLAY': ':0', 03:26:31 INFO - 'HOME': '/home/cltbld', 03:26:31 INFO - 'LANG': 'en_US.UTF-8', 03:26:31 INFO - 'LANGUAGE': 'en_US:en', 03:26:31 INFO - 'LOGNAME': 'cltbld', 03:26:31 INFO - 'MAIL': '/var/mail/cltbld', 03:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:31 INFO - 'MOZ_NO_REMOTE': '1', 03:26:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:31 INFO - 'NO_EM_RESTART': '1', 03:26:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:31 INFO - 'PWD': '/builds/slave/test', 03:26:31 INFO - 'SHELL': '/bin/bash', 03:26:31 INFO - 'SHLVL': '1', 03:26:31 INFO - 'TERM': 'linux', 03:26:31 INFO - 'TMOUT': '86400', 03:26:31 INFO - 'USER': 'cltbld', 03:26:31 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:31 INFO - '_': '/tools/buildbot/bin/python'} 03:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:31 INFO - Downloading/unpacking jsonschema==2.5.1 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:26:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:26:33 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 03:26:33 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 03:26:33 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 03:26:33 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 03:26:33 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 03:26:33 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 03:26:33 INFO - Downloading functools32-3.2.3-2.tar.gz 03:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:26:34 INFO - warning: no files found matching '*.txt' 03:26:34 INFO - no previously-included directories found matching 'build' 03:26:34 INFO - no previously-included directories found matching 'dist' 03:26:34 INFO - no previously-included directories found matching '.git*' 03:26:34 INFO - Installing collected packages: jsonschema, functools32 03:26:34 INFO - Running setup.py install for functools32 03:26:34 INFO - warning: no files found matching '*.txt' 03:26:34 INFO - no previously-included directories found matching 'build' 03:26:34 INFO - no previously-included directories found matching 'dist' 03:26:34 INFO - no previously-included directories found matching '.git*' 03:26:34 INFO - Successfully installed jsonschema functools32 03:26:34 INFO - Cleaning up... 03:26:34 INFO - Return code: 0 03:26:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:34 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:34 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 03:26:34 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 03:26:34 INFO - Using env: {'DISPLAY': ':0', 03:26:34 INFO - 'HOME': '/home/cltbld', 03:26:34 INFO - 'LANG': 'en_US.UTF-8', 03:26:34 INFO - 'LANGUAGE': 'en_US:en', 03:26:34 INFO - 'LOGNAME': 'cltbld', 03:26:34 INFO - 'MAIL': '/var/mail/cltbld', 03:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:34 INFO - 'MOZ_NO_REMOTE': '1', 03:26:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:34 INFO - 'NO_EM_RESTART': '1', 03:26:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:34 INFO - 'PWD': '/builds/slave/test', 03:26:34 INFO - 'SHELL': '/bin/bash', 03:26:34 INFO - 'SHLVL': '1', 03:26:34 INFO - 'TERM': 'linux', 03:26:34 INFO - 'TMOUT': '86400', 03:26:34 INFO - 'USER': 'cltbld', 03:26:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:34 INFO - '_': '/tools/buildbot/bin/python'} 03:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:26:34 INFO - Cleaning up... 03:26:34 INFO - Return code: 0 03:26:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:34 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:34 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 03:26:34 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 03:26:34 INFO - Using env: {'DISPLAY': ':0', 03:26:34 INFO - 'HOME': '/home/cltbld', 03:26:34 INFO - 'LANG': 'en_US.UTF-8', 03:26:34 INFO - 'LANGUAGE': 'en_US:en', 03:26:34 INFO - 'LOGNAME': 'cltbld', 03:26:34 INFO - 'MAIL': '/var/mail/cltbld', 03:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:34 INFO - 'MOZ_NO_REMOTE': '1', 03:26:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:34 INFO - 'NO_EM_RESTART': '1', 03:26:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:34 INFO - 'PWD': '/builds/slave/test', 03:26:34 INFO - 'SHELL': '/bin/bash', 03:26:34 INFO - 'SHLVL': '1', 03:26:34 INFO - 'TERM': 'linux', 03:26:34 INFO - 'TMOUT': '86400', 03:26:34 INFO - 'USER': 'cltbld', 03:26:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:34 INFO - '_': '/tools/buildbot/bin/python'} 03:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:34 INFO - Downloading/unpacking blobuploader==1.2.4 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:34 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 03:26:36 INFO - Downloading blobuploader-1.2.4.tar.gz 03:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:26:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:26:36 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 03:26:36 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 03:26:36 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 03:26:36 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 03:26:36 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 03:26:36 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 03:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:26:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26: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 03:26:37 INFO - Downloading docopt-0.6.1.tar.gz 03:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:26:37 INFO - Installing collected packages: blobuploader, requests, docopt 03:26:37 INFO - Running setup.py install for blobuploader 03:26:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:26:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:26:37 INFO - Running setup.py install for requests 03:26:37 INFO - Running setup.py install for docopt 03:26:37 INFO - Successfully installed blobuploader requests docopt 03:26:37 INFO - Cleaning up... 03:26:38 INFO - Return code: 0 03:26:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:38 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:38 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 03:26:38 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 03:26:38 INFO - Using env: {'DISPLAY': ':0', 03:26:38 INFO - 'HOME': '/home/cltbld', 03:26:38 INFO - 'LANG': 'en_US.UTF-8', 03:26:38 INFO - 'LANGUAGE': 'en_US:en', 03:26:38 INFO - 'LOGNAME': 'cltbld', 03:26:38 INFO - 'MAIL': '/var/mail/cltbld', 03:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:38 INFO - 'MOZ_NO_REMOTE': '1', 03:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:38 INFO - 'NO_EM_RESTART': '1', 03:26:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:38 INFO - 'PWD': '/builds/slave/test', 03:26:38 INFO - 'SHELL': '/bin/bash', 03:26:38 INFO - 'SHLVL': '1', 03:26:38 INFO - 'TERM': 'linux', 03:26:38 INFO - 'TMOUT': '86400', 03:26:38 INFO - 'USER': 'cltbld', 03:26:38 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:38 INFO - '_': '/tools/buildbot/bin/python'} 03:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:38 INFO - Running setup.py (path:/tmp/pip-XNDwBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:38 INFO - Running setup.py (path:/tmp/pip-3Pcs9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:38 INFO - Running setup.py (path:/tmp/pip-gmYVxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:38 INFO - Running setup.py (path:/tmp/pip-ErGExU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:38 INFO - Running setup.py (path:/tmp/pip-F4VPi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:38 INFO - Running setup.py (path:/tmp/pip-hXCwGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:38 INFO - Running setup.py (path:/tmp/pip-Q57aqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:38 INFO - Running setup.py (path:/tmp/pip-ljHKt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:38 INFO - Running setup.py (path:/tmp/pip-PUeODt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:38 INFO - Running setup.py (path:/tmp/pip-EQ0IaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:38 INFO - Running setup.py (path:/tmp/pip-qggqYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:39 INFO - Running setup.py (path:/tmp/pip-gPhbPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:39 INFO - Running setup.py (path:/tmp/pip-xFxbLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:39 INFO - Running setup.py (path:/tmp/pip-wzoRJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:39 INFO - Running setup.py (path:/tmp/pip-Ndqxcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:39 INFO - Running setup.py (path:/tmp/pip-IkKvIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:39 INFO - Running setup.py (path:/tmp/pip-HXA_By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:26:39 INFO - Running setup.py install for manifestparser 03:26:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:26:39 INFO - Running setup.py install for mozcrash 03:26:39 INFO - Running setup.py install for mozdebug 03:26:39 INFO - Running setup.py install for mozdevice 03:26:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:26:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:26:39 INFO - Running setup.py install for mozfile 03:26:39 INFO - Running setup.py install for mozhttpd 03:26:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozinfo 03:26:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozInstall 03:26:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozleak 03:26:40 INFO - Running setup.py install for mozlog 03:26:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for moznetwork 03:26:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozprocess 03:26:40 INFO - Running setup.py install for mozprofile 03:26:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozrunner 03:26:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:26:40 INFO - Running setup.py install for mozscreenshot 03:26:41 INFO - Running setup.py install for moztest 03:26:41 INFO - Running setup.py install for mozversion 03:26:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:26:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:26:41 INFO - Cleaning up... 03:26:41 INFO - Return code: 0 03:26:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:41 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:41 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 03:26:41 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 03:26:41 INFO - Using env: {'DISPLAY': ':0', 03:26:41 INFO - 'HOME': '/home/cltbld', 03:26:41 INFO - 'LANG': 'en_US.UTF-8', 03:26:41 INFO - 'LANGUAGE': 'en_US:en', 03:26:41 INFO - 'LOGNAME': 'cltbld', 03:26:41 INFO - 'MAIL': '/var/mail/cltbld', 03:26:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:41 INFO - 'MOZ_NO_REMOTE': '1', 03:26:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:41 INFO - 'NO_EM_RESTART': '1', 03:26:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:41 INFO - 'PWD': '/builds/slave/test', 03:26:41 INFO - 'SHELL': '/bin/bash', 03:26:41 INFO - 'SHLVL': '1', 03:26:41 INFO - 'TERM': 'linux', 03:26:41 INFO - 'TMOUT': '86400', 03:26:41 INFO - 'USER': 'cltbld', 03:26:41 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:41 INFO - '_': '/tools/buildbot/bin/python'} 03:26:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:26:41 INFO - Running setup.py (path:/tmp/pip-hRw_NI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:26:41 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)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:26:41 INFO - Running setup.py (path:/tmp/pip-EPvRJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:26:41 INFO - Running setup.py (path:/tmp/pip-pPAitB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:26:41 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)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:26:41 INFO - Running setup.py (path:/tmp/pip-3J_OM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:26:41 INFO - Running setup.py (path:/tmp/pip-iEJGha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:26:41 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)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:26:41 INFO - Running setup.py (path:/tmp/pip-o2S61x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:26:41 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)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:26:41 INFO - Running setup.py (path:/tmp/pip-qJkFgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:26:41 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)) 03:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:26:42 INFO - Running setup.py (path:/tmp/pip-NSa4iJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:26:42 INFO - Running setup.py (path:/tmp/pip-ejx0tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:26:42 INFO - Running setup.py (path:/tmp/pip-99k0nD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:26:42 INFO - Running setup.py (path:/tmp/pip-z4cs22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:26:42 INFO - Running setup.py (path:/tmp/pip-9g0auT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:26:42 INFO - Running setup.py (path:/tmp/pip-lrD95D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:26:42 INFO - Running setup.py (path:/tmp/pip-5Eljeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:42 INFO - Running setup.py (path:/tmp/pip-VxSptH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:26:42 INFO - Running setup.py (path:/tmp/pip-cXXJpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:26:42 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)) 03:26:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:26:42 INFO - Running setup.py (path:/tmp/pip-y70NmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:26:42 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)) 03:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:26:42 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)) 03:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:26:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:26:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:26:44 INFO - Downloading blessings-1.6.tar.gz 03:26:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:26:44 INFO - Installing collected packages: blessings 03:26:44 INFO - Running setup.py install for blessings 03:26:44 INFO - Successfully installed blessings 03:26:44 INFO - Cleaning up... 03:26:44 INFO - Return code: 0 03:26:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:45 INFO - Reading from file tmpfile_stdout 03:26:45 INFO - Current package versions: 03:26:45 INFO - argparse == 1.2.1 03:26:45 INFO - blessings == 1.6 03:26:45 INFO - blobuploader == 1.2.4 03:26:45 INFO - docopt == 0.6.1 03:26:45 INFO - functools32 == 3.2.3-2 03:26:45 INFO - jsonschema == 2.5.1 03:26:45 INFO - manifestparser == 1.1 03:26:45 INFO - mozInstall == 1.12 03:26:45 INFO - mozcrash == 0.17 03:26:45 INFO - mozdebug == 0.1 03:26:45 INFO - mozdevice == 0.48 03:26:45 INFO - mozfile == 1.2 03:26:45 INFO - mozhttpd == 0.7 03:26:45 INFO - mozinfo == 0.9 03:26:45 INFO - mozleak == 0.1 03:26:45 INFO - mozlog == 3.4 03:26:45 INFO - moznetwork == 0.27 03:26:45 INFO - mozprocess == 0.23 03:26:45 INFO - mozprofile == 0.28 03:26:45 INFO - mozrunner == 6.12 03:26:45 INFO - mozscreenshot == 0.1 03:26:45 INFO - mozsystemmonitor == 0.3 03:26:45 INFO - moztest == 0.7 03:26:45 INFO - mozversion == 1.4 03:26:45 INFO - psutil == 3.1.1 03:26:45 INFO - requests == 1.2.3 03:26:45 INFO - wsgiref == 0.1.2 03:26:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:26:45 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')]} 03:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 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')]} 03:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:45 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 03:26:45 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 03:26:45 INFO - Using env: {'DISPLAY': ':0', 03:26:45 INFO - 'HOME': '/home/cltbld', 03:26:45 INFO - 'LANG': 'en_US.UTF-8', 03:26:45 INFO - 'LANGUAGE': 'en_US:en', 03:26:45 INFO - 'LOGNAME': 'cltbld', 03:26:45 INFO - 'MAIL': '/var/mail/cltbld', 03:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:45 INFO - 'MOZ_NO_REMOTE': '1', 03:26:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:45 INFO - 'NO_EM_RESTART': '1', 03:26:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:45 INFO - 'PWD': '/builds/slave/test', 03:26:45 INFO - 'SHELL': '/bin/bash', 03:26:45 INFO - 'SHLVL': '1', 03:26:45 INFO - 'TERM': 'linux', 03:26:45 INFO - 'TMOUT': '86400', 03:26:45 INFO - 'USER': 'cltbld', 03:26:45 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:45 INFO - '_': '/tools/buildbot/bin/python'} 03:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 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)) 03:26:45 INFO - Cleaning up... 03:26:45 INFO - Return code: 0 03:26:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:26:45 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')]} 03:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 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')]} 03:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:26:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:26:45 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 0x7f820ae0ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', '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 03:26:45 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 03:26:45 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 03:26:45 INFO - Using env: {'DISPLAY': ':0', 03:26:45 INFO - 'HOME': '/home/cltbld', 03:26:45 INFO - 'LANG': 'en_US.UTF-8', 03:26:45 INFO - 'LANGUAGE': 'en_US:en', 03:26:45 INFO - 'LOGNAME': 'cltbld', 03:26:45 INFO - 'MAIL': '/var/mail/cltbld', 03:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:45 INFO - 'MOZ_NO_REMOTE': '1', 03:26:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:26:45 INFO - 'NO_EM_RESTART': '1', 03:26:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:26:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:45 INFO - 'PWD': '/builds/slave/test', 03:26:45 INFO - 'SHELL': '/bin/bash', 03:26:45 INFO - 'SHLVL': '1', 03:26:45 INFO - 'TERM': 'linux', 03:26:45 INFO - 'TMOUT': '86400', 03:26:45 INFO - 'USER': 'cltbld', 03:26:45 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:45 INFO - '_': '/tools/buildbot/bin/python'} 03:26:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:26:45 INFO - Cleaning up... 03:26:45 INFO - Return code: 0 03:26:45 INFO - Running post-action listener: _resource_record_post_action 03:26:45 INFO - Running post-action listener: _start_resource_monitoring 03:26:45 INFO - Starting resource monitoring. 03:26:45 INFO - [mozharness: 2016-11-09 11:26:45.580455Z] Finished create-virtualenv step (success) 03:26:45 INFO - [mozharness: 2016-11-09 11:26:45.580968Z] Running install step. 03:26:45 INFO - Running pre-action listener: _resource_record_pre_action 03:26:45 INFO - Running main action method: install 03:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:26:45 INFO - Reading from file tmpfile_stdout 03:26:45 INFO - Detecting whether we're running mozinstall >=1.0... 03:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:26:45 INFO - Reading from file tmpfile_stdout 03:26:45 INFO - Output received: 03:26:45 INFO - Usage: mozinstall [options] installer 03:26:45 INFO - Options: 03:26:45 INFO - -h, --help show this help message and exit 03:26:45 INFO - -d DEST, --destination=DEST 03:26:45 INFO - Directory to install application into. [default: 03:26:45 INFO - "/builds/slave/test"] 03:26:45 INFO - --app=APP Application being installed. [default: firefox] 03:26:45 INFO - mkdir: /builds/slave/test/build/application 03:26:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:26:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:27:02 INFO - Reading from file tmpfile_stdout 03:27:02 INFO - Output received: 03:27:02 INFO - /builds/slave/test/build/application/firefox/firefox 03:27:02 INFO - Running post-action listener: _resource_record_post_action 03:27:02 INFO - [mozharness: 2016-11-09 11:27:02.529787Z] Finished install step (success) 03:27:02 INFO - [mozharness: 2016-11-09 11:27:02.530032Z] Running run-tests step. 03:27:02 INFO - Running pre-action listener: _resource_record_pre_action 03:27:02 INFO - Running main action method: run_tests 03:27:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:27:02 INFO - Python 2.7.3 03:27:02 INFO - Return code: 0 03:27:02 INFO - grabbing minidump binary from tooltool 03:27:02 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')]} 03:27:02 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 0x1ca1f60>, '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 0x1ca2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ca31d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:27:02 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 03:27:02 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 03:27:02 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 03:27:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:27:02 INFO - Return code: 0 03:27:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:27:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:27:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:27:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:27:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:27:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:27:02 INFO - Using env: {'DISPLAY': ':0', 03:27:02 INFO - 'HOME': '/home/cltbld', 03:27:02 INFO - 'LANG': 'en_US.UTF-8', 03:27:02 INFO - 'LANGUAGE': 'en_US:en', 03:27:02 INFO - 'LOGNAME': 'cltbld', 03:27:02 INFO - 'MAIL': '/var/mail/cltbld', 03:27:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:02 INFO - 'MOZ_NO_REMOTE': '1', 03:27:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:02 INFO - 'NO_EM_RESTART': '1', 03:27:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:02 INFO - 'PWD': '/builds/slave/test', 03:27:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:02 INFO - 'SHELL': '/bin/bash', 03:27:02 INFO - 'SHLVL': '1', 03:27:02 INFO - 'TERM': 'linux', 03:27:02 INFO - 'TMOUT': '86400', 03:27:02 INFO - 'USER': 'cltbld', 03:27:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765', 03:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:02 INFO - '_': '/tools/buildbot/bin/python'} 03:27:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:27:02 INFO - mozversion application_buildid: 20161108173621 03:27:02 INFO - mozversion application_changeset: ae3b6bfb810476141647ad681b796ed118062412 03:27:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:27:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:27:02 INFO - mozversion application_name: Firefox 03:27:02 INFO - mozversion application_remotingname: firefox-dev 03:27:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:27:02 INFO - mozversion application_vendor: Mozilla 03:27:02 INFO - mozversion application_version: 51.0a2 03:27:02 INFO - mozversion platform_buildid: 20161108173621 03:27:02 INFO - mozversion platform_changeset: ae3b6bfb810476141647ad681b796ed118062412 03:27:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:27:02 INFO - mozversion platform_version: 51.0a2 03:27:02 INFO - using testdate: 1478690822 03:27:02 INFO - actual date: 1478690822 03:27:02 INFO - starting webserver on 'localhost:38626' 03:27:02 INFO - SUITE-START | Running 2 tests 03:27:02 INFO - TEST-START | dromaeo_css 03:27:02 INFO - operating with platform_type : linux_ 03:27:02 INFO - Initialising browser for dromaeo_css test... 03:27:02 INFO - TEST-INFO | started process 19838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4EdQkH/profile http://localhost:38626/getInfo.html) 03:27:06 INFO - PROCESS | 19838 | __metrics Screen width/height:1600/1200 03:27:06 INFO - PROCESS | 19838 | colorDepth:24 03:27:06 INFO - PROCESS | 19838 | Browser inner width/height: 1024/697 03:27:06 INFO - PROCESS | 19838 | __metrics 03:27:07 INFO - PROCESS | 19838 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:27:07 INFO - PROCESS | 19838 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:27:07 INFO - PROCESS | 19838 | console.error: PushService: 03:27:07 INFO - PROCESS | 19838 | stateChangeProcessEnqueue: Error transitioning state 03:27:07 INFO - PROCESS | 19838 | UnknownError 03:27:08 INFO - TEST-INFO | 19838: exit 0 03:27:08 INFO - Browser initialized. 03:27:08 INFO - Running cycle 1/1 for dromaeo_css test... 03:27:08 INFO - TEST-INFO | started process 19927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4EdQkH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:20 INFO - PROCESS | 19927 | RSS: Main: 144584704 03:27:20 INFO - PROCESS | 19927 | 03:28:11 INFO - PROCESS | 19927 | 1478690891214 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:30:20 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38626/tests/dromaeo/cssquery-ext.html) 03:30:21 INFO - PROCESS | 19927 | RSS: Main: 241692672 03:30:21 INFO - PROCESS | 19927 | 03:33:23 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-ext.html (next: http://localhost:38626/tests/dromaeo/cssquery-jquery.html) 03:33:24 INFO - PROCESS | 19927 | RSS: Main: 232333312 03:33:24 INFO - PROCESS | 19927 | 03:36:26 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38626/tests/dromaeo/cssquery-mootools.html) 03:36:27 INFO - PROCESS | 19927 | RSS: Main: 175104000 03:36:27 INFO - PROCESS | 19927 | 03:39:29 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38626/tests/dromaeo/cssquery-prototype.html) 03:39:29 INFO - PROCESS | 19927 | RSS: Main: 231944192 03:39:29 INFO - PROCESS | 19927 | 03:42:32 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38626/tests/dromaeo/cssquery-yui.html) 03:42:32 INFO - PROCESS | 19927 | RSS: Main: 178425856 03:42:32 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | Cycle 1(1): loaded http://localhost:38626/tests/dromaeo/cssquery-yui.html (next: http://localhost:38626/tests/dromaeo/cssquery-dojo.html) 03:45:35 INFO - PROCESS | 19927 | RSS: Main: 268615680 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | __start_tp_report 03:45:35 INFO - PROCESS | 19927 | _x_x_mozilla_page_load 03:45:35 INFO - PROCESS | 19927 | _x_x_mozilla_page_load_details 03:45:35 INFO - PROCESS | 19927 | |i|pagename|runs| 03:45:35 INFO - PROCESS | 19927 | |0;dojo.html;3133.263683034487;3169.6037995250595;3173.9365212695734;3199.040287913628;3211.9839400802994;5513.531349835263;5580.8604784880345;5586.162075688649;5601.747921343539;5619.522340601054;5560.332760068787;5614.466625670565;5622.4939755422;5630.971845140769;5642.887142257163;5021.422536408314;5059.16523773577;5102.489751024905;5150.1759718445055;5180;5001.874953126165;5108.66793658411;5158.690478571279;5164.586833053347;5171.405288391827;4963.950360496385;5041.495850414947;5071.746412679369;5092.8472145835685;5096;5094.46508116648;5216.66091704038;5219.164933610642;5224.242484839677;5228.111221092423;5032.521910418541;5100.5409513143995;5159.690418574897;5175.534201921808;5201.063808514466;5384.6499977501335;5430.321209848769;5441.211024401479;5457.781688732446;5482.342118945712;5875.412458754133;5880.294364676266;5886.411358864122;5983.491403230731;5986.580939334249;6009.549283803733;6024.09638554216;6024.939750602482;6028.09578563219;6031.095335699636;5448.91826622597;5527.53015993641;5574.60977731559;5597.552195824324;5612.85967850807;6121.4490695837585;6146;6153;6163.075538669235;6171.537134714914;5919.792807251725;5926.496247818897;5926.851828704317;5935.376785437553;5937.465628093492;5768.942310576925;5800.622959507618;5921.703914804242;5944.7027648617395;5946.256717910262;5948.256467941507;5959;5964.552658550626;5981.102834574848;5990.5507086968655;5941.494972927349;5991.910121348184;5996.220491336186;6011.579189456784;6015.759369625166;4479.238529450001;4508.211063063951;4565.383673204141;4603.562661547147;4645.814167433265;4071.75569465833;4096.692748043909;4120.17596480705;4124.298869192245;4127.731697439657;5736.311642602915;5739.196512488255;5739.684317362585;5746;5749.108888122348;5815.76736930518;5872.500837428781;5941.376155503696;6024.397560244028;6026.90959635597;5644.350899646511;5669.433056694297;5689.544836413077;5714.942850571524;5716.114002329646;5162.8967420652125;5170.819021334235;5176.197689358156;5181.326427564392;5203.869903252374;5900.586958912921;5996.820095397145;6081;6119.2350956130485;6139.877202456015;5964.165016897725;5983.461488466059;6017.398260173947;6060.181875446759;6105.084237364431;6198.101275315144;6211.192544969125;6223.813285601439;6232.8130156095385;6235.906461403173;5749.310082790092;5935.0503919372695;5954.196183515343;5956.344802071682;5984.670843103497;5827.79602713903;5960.850978725566;6016.548758843017;6019.789307374221;6090.878182436238;5868.2077919482035;5915.497182739516;5920.023196172583;5930.05119180929;5931.970340148272;5848.239728835224;5861.3845546217035;5866.032104702847;5869.413058694096;5873.647581145145;5584.888302233851;5666.206731057738;5704.515116215002;5752.913706294493;5796.1305804129715;5879.588428809942;5880.617759845596;5882.205902203319;5887.264091988502;5907.32065812437;5548.0845660467185;5723.627964182315;5737.540996720169;5747.19539264495;5747.396523364986 03:45:35 INFO - PROCESS | 19927 | |1;ext.html;593.9732712027958;632.0266789144716;632.8038308124479;644.0532417346502;644.8806970710417;34484;37438.812805936;45324;45381.7730911345;45437.09125817482;48780.75609621947;49284;49359.75320123394;49381.50618493814;49425;9173.128552787475;9307.860382094274;9313.95343023284;9342;9357.906420935806;8388.664453421887;8578.485290882536;10906.61826836061;11134.777304453906;11154.386508742016;11332.660020199366;11426.257293275952;11435.714107147347;11438.828417573743;11440;11158.832617510743;11241.550337986471;11255.606053788078;11255.774884502263;11261.4369281536;11011.779764404668;11058.170637202162;11110.333379997226;11117.22179447439;11122.332660040423;46126;46459;46510.30234546484;46510.53489465096;46589.76705116487;3615.7830530168276;3750.324941510527;3772.4529943158363;3880.689544836406;4149.128682976579;33565.83217083899;33597.49603755945;33631;33752.662473375196;34026;4292.141571685675;4363.345498175299;4394.78026098697;4404.163208990313;4447.08834688888;358653;360168.1949541518;360700.9999999974;361098;361510;37141.257174856895;62222.688886556185;62231.377686223466;62355;62373.064404033976;39252.803735981135;39384.212315753524;39406.60593394087;39407.80296098559;39421;17373.47879563615;17480.388186413413;17483.213255403538;17484.82515174832;17515.64968700619;13254.668633284053;14667.193304368362;14761.704765904622;14762.778558321634;14777.261136943325;8896.911030889738;9239.076092390842;9449.196818270386;9452.90547094534;9462.858057129148;3630.2739452109686;3658.63413658632;3662.157703728104;3670.6696397324367;3672.5592928848173;1043.5199808088212;1099;1127.6617014895662;1164.8369228308043;1178.269472926791;34504.82747586296;34663;34796.13009674779;34932;34955.475667865;1162.1864694713734;1167.6730515455686;1180.6635108993896;1231.2981600487635;1245.5391505143155;17815.821841781417;18303.267869285344;18403.447896563124;18420.907895460437;18446.81553184451;585664;597125;599019;599051;599460;61341.386586133565;61547.38452615506;62079.689601551705;62109.689451552455;62198;1288.5876519513665;1303.4004357995238;1337.725766217923;1413.54059930523;1438.8273407190995;29988.850055749583;35067.82466087653;35079.298414031065;35532.644673553965;35566.93299201028;52649.736751316;53235.73382133064;53255.7337213327;53266.73366633142;53297.20054199112;400532;402140;404766;405312;405552;30110.548341774454;30756.846215769674;30784.692153078184;30930;32650;49997;50238.246426304366;51120;51206.23190652069;51269.48730512647;42243.1551368977;42477;43038.56961430471;43993;44615;41219.38170927498;41438.79280603577;41712;41754.16491670089;43362.56637433711;41436.792816035726;41972;42051.57948420477;42104.1579168421;42925.356119657605;36259;38338;38372.61627383802;38929.221415572094;39126;29209.707902920698;29220.561691574218;29222.707772922;29421.85289073541;29565 03:45:35 INFO - PROCESS | 19927 | |2;jquery.html;397.72556935714374;408.44451545897573;416.5418040155829;419.6307249620336;430.26854347609054;14302.213378264192;16249.187540622981;19890.20439182433;20659.483512912186;20986.895065524688;19745.703814442793;19783.307584234553;20333.186672533117;20679.172833086657;20930.16279348828;5786.652800831943;6008.879822403538;6022.277326720778;6062;6237.968810155943;4164.791760411982;6036.63780173191;6075.058365953284;6076.058210977306;6076.240469941257;4361.520232774408;4370.56294370562;4406.272964960777;6842.349976752201;6890.138732658418;6640.535162538624;6700.631465269408;6897.965510172442;6906.482013848957;6911.101556797635;6000.819975400745;6005.39946005396;6014.278286605635;6014.30835453924;6026.216591843032;14410.567682969528;14654.56036318912;14868.479603213835;14910.776338354824;15004.849951500455;2378.084437491561;2379.07216185686;2621.89512419503;2699.068821256683;2888.638920134983;10645.20160987929;11423.029042531394;13550.186988780606;15530.145841978687;15896.76154857678;1946.143696773415;1983.5140390604395;2012.4968757810548;2046.6418376784004;2062.391594479632;71048.6447567764;82909.17090829136;85474;86271.13728862756;87992.56003720069;9603.567839447242;10056.245781566411;10119.089281964658;10315.896841031643;11729.648110556696;14525.491607793676;14564.344604492824;14616.196109213883;14631.853681463263;14645.560633181021;8592.032967032992;8811.559422028873;8931.151540603632;8976.730698079067;9007.23438507721;9074.773130671787;9077.68228112013;9094.363394562317;9287.48918809472;9319.068093190626;4442.222611043055;4470.418845550058;4545.0000999779995;4552.681312308173;4598.609118224988;2028.361155986331;2101.1805395895612;2104.537001859619;2134.3916983659583;2186.7157269554855;882.82343531294;967.4533888353058;996.9202463802878;1042.8227201375785;1056.8943105689523;19068.71396929047;20597.794022059887;20691.4827129323;21195;21636.134554617955;1037.9117774989058;1047.0994944347856;1047.5757318286107;1084.1110289562482;1112.554978008803;11193.94403027996;11533.365664888344;11534.98228270705;11827.290362578273;11892.464839082262;493476;495572;498390;542713;553149;98785;102976.48511757543;106566;106934.46532767286;109591;1086.179934149712;1120.3054106454051;1131.7114135895458;1167.3229526874393;1172.6657902497748;13717.382717777524;17548.656508662574;18394.448166555027;18524;18593.72109418333;30352.54471182978;37851.62148378481;37855;42898.78550607227;43665.78167109144;56728.71635641796;57524.42475575358;58287.417125829896;58539.70730146322;60043.39956600378;8362;8448.112948140602;8568.957155214184;8633.30933525304;8684.565771711515;11899.405029748652;12165.330906799856;12172.14794964344;12522.248665080124;12569.93715031419;10452.68641940743;10560.947195263974;10648.89351106479;10697.572097116028;10953.507092180711;10378.481075946022;10429.63496277657;10494.370337779757;10640.946795265974;10756.354618722902;10516.316439431412;10552.261341706007;10764.46177691128;10783.51474183648;10855.185861060294;8121.675132994614;8270.958645206976;8448.746537603882;8487;8714.738557843275;9158.633654653739;10209.438480883622;10406.323588966692;10534.157267418435;10599.523021464207 03:45:35 INFO - PROCESS | 19927 | |3;mootools.html;1481.281578434459;1518.5140754958413;1637.9262933168013;1723.6208034232463;1794.165712943481;8531.232189102979;9304.627814887397;9310.162085412303;9311;9315.860262096074;8078.515289082645;8295.585220738969;8607.91392086079;8801.691940782073;9126.589303481343;2455.4229756007326;2482.900683972648;2483.1433155561394;2485.403503159247;2485.801135909132;2386.546556154325;2520.621906713998;2521.004203339689;2521.735217802132;2523.621456781479;2435.768601982809;2444.535538247736;2509.4228327484598;2513.49730053989;2513.736057713941;2424.9393765155924;2438.0979037756056;2448.8285819992607;2452.7056753189554;2454.6686197363483;2410.6022506286617;2422.91519796808;2430.902763889442;2431.4043059450546;2467.629855521668;9104.36269461138;9139.771505712344;9209.355345125843;9218.677346292914;9221.077892210726;5440.809571664972;5572.164175373727;5574.052411090124;5576.302962129734;5577.749001294951;3211.694888985566;3304.9008529744146;3350.530925670408;3360.83195840207;3365.612954510238;1222.1689251309106;1223.608445297505;1231.34123244064;1255.3095797311441;1268.226381907036;12631.873681263161;12677.619671409873;12711.110222284444;12727.42726577297;12740.681482962907;4803.519648035239;4810.855674329776;4821.855344339676;4909.582685471775;5015.774290156952;10824.783504330027;10829.404382759023;10832.67501974942;10834.241603087867;10841.620543280947;5512.39363669996;5558.722063896789;5565.1095824667855;5566.35986861508;5569;4411.757353345597;4552.658550608718;4982.526659967298;5088.1350170471105;5127;4210.59999300066;4264.402983582301;4379.715318504287;4419.513853476115;4448.666350023761;1411.1110000699698;1448.4061534770694;1456.7814827775705;1457.8177727784027;1463.0782606957548;848.6393282854762;910.6721580231111;919.1727445299284;928.2991341537096;958.7411398922252;5823.767049317979;5920.141579470953;5929.407059294036;5983.640981541122;6344.270408902943;936.3679516326453;948.7201275623707;964.9710508684751;987.9012098790064;1013.9442330671884;3821.178446633987;3931.2334094851803;4032.213718324957;4112.157007813391;4298.634616057608;11371.658850234506;11616.94191529037;11644.301341919512;11645.88354116448;11668.124890633237;11455.369954652408;11536.250143740797;11536.423178841025;11539.249948753304;11615.59345422923;8012.238837310446;8254.917450825415;8359.289460395812;8571.271441927382;8576.871346929676;5901.1443340714495;5939.287285525592;6080.452759251689;6090.786822461193;6119.35746746603;10180.898191018292;10541.209409294486;10565.894341056492;10568.788624227625;10670.199735019749;10677.51951162184;10729.46352682347;10755.300905441121;10759.408232547285;10762.892371076188;7155.892661610184;7401;7847.725329613436;8081.797955051171;8229.05365882931;8461.61922713467;8948.776280593038;8960.238379737795;8963.417377870417;8979.191872731486;7731.729389471341;7896.526208427513;7951.204879512234;7977.162397948132;7986;7514.736984205527;7758.107817600822;7781.572013539309;7789.961050194939;7799.337056350047;6899.517033807585;6941.097657304518;6956.165260168812;6957.686904089227;6959.965200173967;4973.701577905337;5127.077126117335;5132.79468821243;5175.870603234798;5350.652207606493;5136.460671629425;5182.844514664566;5232.372115346183;5350.063738845609;5372.059889519241 03:45:35 INFO - PROCESS | 19927 | |4;prototype.html;322.3327711636911;334.22293168383504;335.138693557557;338.73578608685204;342.0320493004797;5481.917771233429;5527.314169178399;5565.526930210937;5590.552755779538;5604.794969081647;5513.531349835269;5523.696196709179;5530.7323997161075;5535.481355983028;5547.889042219153;4313.309870420733;4319.611234988851;4338.2191205582985;4345.217860785057;4351.303791393378;4016.919661606766;4087.386891966199;4249.150169966019;4261.190373828978;4356.80394382252;4304.97847510762;4308.230298488822;4310.353446982962;4317.287647538153;4341.7177883437635;4300.268954277781;4310.978445107786;4314.288142456491;4340.696151269412;4347.826086956518;3561.626029266916;3650.34293827111;3786.8106594670157;3820.2168555446065;3861.8455261789495;5385.21914322425;5393.1370980642905;5393.649412788156;5396.3524377075;5412.972935135299;4853.563179313879;4934.210526315808;4951.975240123812;4955.5044495550155;4957.769003948238;5624.606277560572;5633.6619802811965;5666.971665141689;5675.233843431125;5675.63108397957;4128.174365126986;4135.6898232632675;4147.751134931914;4152.667786577066;4198.580141985807;5951.9107213391835;5979.611325263887;5987.43119403656;6020.096985452217;6023.337432882379;3995.260876737813;4188.120494696087;4220.493540775126;4229.154169166179;4243.5119961204855;4272.636825869774;4277.898523570449;4281.657467402601;4282.593153650399;4284.928767808048;4879.585235254972;4891.144049791273;4909.631777616693;4913.828016019422;4915.066137433876;5563.5271001964475;5824;5865.736041878125;5870.618409803435;5872.17789509469;1465.5310300703673;1476.9587440854173;1480.1267252320968;1482.3922191901484;1484.4210757804465;904.8009437923646;920.4490433619372;947.4220725357526;948.1656142594611;952.8189643967625;4990.452959582541;5029.270755740397;5029.798808047637;5035.84892453227;5035.974820125876;5755.568332375088;5832.475077243069;5896.380880007623;6052.485538729169;6058.4244496772735;5663.235463212496;5696.544276457874;5702.173184888251;5736.569757268138;5740.56945729072;3080.86136123875;3166.6358368787883;3177.4757165067967;3200.9839950800097;3222.5327327537843;6246.219222597176;6252.656103914328;6260.5617606768;6263.686815659199;6281.7173227204885;5839.9124013139835;6175.023992322504;6215.627062376272;6234.501239900797;6236;6200.751969921243;6219.191505104308;6230.252369715572;6245.406686364788;6245.812625621239;6046.939530604637;6080.422359875804;6080.69596520181;6087.695615219132;6096.1465394845645;5844.912326315024;5957.4042595740075;5966.701664916824;5977.1034344846885;5996.190514280516;6195.504359651127;6235.844103897439;6253;6262.436380725574;6273.905891411541;4285.785710714389;4324.5675432457765;4377.146456440963;4382.298832186835;4384.2765943620225;6042.305134909366;6058.242719660043;6062.696865156637;6067.088867006879;6095.786647467317;5909.734061967136;5916.645001299764;5939.4357536034;5952.970235148797;5955.052249136705;5697.544196464356;5809.5352371811;5843.766249349978;5875.412458754261;5939.3963629818845;5874.353821079592;5884.735186916513;5890.440408161217;5894.204282421818;5908.852278693067;5759.884802303846;5905.970470147622;5943.494802941798;5962.612430192024;5968.373320801254;5774.39368866263;5786.797462088806;5805.767769289181;5805.82582522525;5815.476607105381 03:45:35 INFO - PROCESS | 19927 | |5;yui.html;793.0919097633214;812.2730156509924;829.9626516806743;857.3569822633024;859.896812382514;15989.04065756054;16885.831141688595;17297.48107556772;17462.388816391427;17474.126293685298;14846.851531484683;14852.48016319429;14984;15007.399704011827;15017.324220410108;858.1204265627734;869.070094998352;878.9340799440035;896.2337201692557;905.3979103895902;893.3076865429312;897.5108565831638;898.0525545549443;898.4788822482977;898.510311880027;936.419419959626;937.1331518345503;939.4175611121115;940.9764755881089;941.0730430525927;931.6087243357772;935.5696379665328;937.1986951156765;937.6483818568037;937.7655586103474;688.6763221285987;689.4002218070311;689.5896941319957;689.6034780001519;689.6310473896491;16851.157442127846;16980.915095424443;16987.40544080951;17013.23440445183;17021.744673829904;15545.766813497807;16152.919235403866;16229.918850405673;16719.414820481346;16779.748303775454;13097.738045239043;13207.537736179187;13505.05464617477;13576.796348054788;13580.728385432236;3994.460747799039;3995.7802320872343;4009.8596549120934;4126.896827579335;4195.895102622429;88425;91066.99999999933;91942.5402872988;92474;93024.53487732586;10853.34879907208;12034.518619255306;12127.575534856414;12198.268103913617;12268.938655306845;22272.3318300448;22350.77649223487;22451.21420750266;22480.88759556192;22550;8921.241694455914;9200.815983680423;9470;9552.331336806357;9561.952190239004;4747.264174053027;4773.9761301193275;4777.331173635694;4817.9518204818205;4921.409430868247;3561.7684850484634;3658.634136586373;3665.248624032067;3672.2288319452678;3677.9264414711556;605.9787907423218;620.4571000374672;624.8312955501989;636.4558302651259;638.6040654794058;713.2083387439937;716.4912911832553;738.7082102569454;801.1027649033003;807.4226927746689;9716.514174291256;10223.693289201336;10483.213758968108;10484.004019618276;10673.412962286993;749.4716225061319;769.2615089514129;789.5105034878412;792.6512334572885;834.3950635789125;4967.254911763264;5141.665791723526;5168.1497284523275;5251.396089449762;5310.522053015247;71655;93873;95869;131899;137800.31099844436;106783;112012;113308.43345783219;113484.59583928809;113942.43028784968;95781;95857;96501.51749241349;97168.51415742876;99601.00398996062;9893.703188904345;9947.86599815405;9985.301028927905;10035.949820250851;10044.698659040241;55085.173722393396;56146;56947;57598.42401575931;57787.711061444425;74912;80089.1991080105;87482.56258718665;89512;90264;12843.871561284268;12906;12906.773405725635;12934.159279646792;13235.735285294433;13074.607761767162;13509.324533773468;13607.659808504866;14041.227732474812;14271.71456570842;11824.58613948542;12156.452959616661;12522.937385313015;12771.80842287385;13163;10407.68776936693;10751.6236931707;10830.945845270724;11020.779584408107;11270.211085223955;11946.223495472766;12844;12888.355582221038;13216.074874758675;13285.734285314047;10943.45282735876;10984.780304394028;11079.612213572485;11362.54549818031;11540.653780386601;10210.234232432449;10983.945080274547;11202.887971120184;11205;11315.603953861575 03:45:35 INFO - PROCESS | 19927 | __end_tp_report 03:45:35 INFO - PROCESS | 19927 | __start_cc_report 03:45:35 INFO - PROCESS | 19927 | _x_x_mozilla_cycle_collect,867 03:45:35 INFO - PROCESS | 19927 | __end_cc_report 03:45:35 INFO - PROCESS | 19927 | __startTimestamp1478691935334__endTimestamp 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | ------- Summary: start ------- 03:45:35 INFO - PROCESS | 19927 | Number of tests: 6 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#0] dojo.html Cycles:165 Average:5554.71 Median:5749.11 stddev:630.45 (11.0%) stddev-sans-first:603.08 03:45:35 INFO - PROCESS | 19927 | Values: 3133.3 3169.6 3173.9 3199.0 3212.0 5513.5 5580.9 5586.2 5601.7 5619.5 5560.3 5614.5 5622.5 5631.0 5642.9 5021.4 5059.2 5102.5 5150.2 5180.0 5001.9 5108.7 5158.7 5164.6 5171.4 4964.0 5041.5 5071.7 5092.8 5096.0 5094.5 5216.7 5219.2 5224.2 5228.1 5032.5 5100.5 5159.7 5175.5 5201.1 5384.6 5430.3 5441.2 5457.8 5482.3 5875.4 5880.3 5886.4 5983.5 5986.6 6009.5 6024.1 6024.9 6028.1 6031.1 5448.9 5527.5 5574.6 5597.6 5612.9 6121.4 6146.0 6153.0 6163.1 6171.5 5919.8 5926.5 5926.9 5935.4 5937.5 5768.9 5800.6 5921.7 5944.7 5946.3 5948.3 5959.0 5964.6 5981.1 5990.6 5941.5 5991.9 5996.2 6011.6 6015.8 4479.2 4508.2 4565.4 4603.6 4645.8 4071.8 4096.7 4120.2 4124.3 4127.7 5736.3 5739.2 5739.7 5746.0 5749.1 5815.8 5872.5 5941.4 6024.4 6026.9 5644.4 5669.4 5689.5 5714.9 5716.1 5162.9 5170.8 5176.2 5181.3 5203.9 5900.6 5996.8 6081.0 6119.2 6139.9 5964.2 5983.5 6017.4 6060.2 6105.1 6198.1 6211.2 6223.8 6232.8 6235.9 5749.3 5935.1 5954.2 5956.3 5984.7 5827.8 5960.9 6016.5 6019.8 6090.9 5868.2 5915.5 5920.0 5930.1 5932.0 5848.2 5861.4 5866.0 5869.4 5873.6 5584.9 5666.2 5704.5 5752.9 5796.1 5879.6 5880.6 5882.2 5887.3 5907.3 5548.1 5723.6 5737.5 5747.2 5747.4 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#1] ext.html Cycles:180 Average:61587.91 Median:38355.31 stddev:123483.26 (321.9%) stddev-sans-first:123744.75 03:45:35 INFO - PROCESS | 19927 | Values: 594.0 632.0 632.8 644.1 644.9 34484.0 37438.8 45324.0 45381.8 45437.1 48780.8 49284.0 49359.8 49381.5 49425.0 9173.1 9307.9 9314.0 9342.0 9357.9 8388.7 8578.5 10906.6 11134.8 11154.4 11332.7 11426.3 11435.7 11438.8 11440.0 11158.8 11241.6 11255.6 11255.8 11261.4 11011.8 11058.2 11110.3 11117.2 11122.3 46126.0 46459.0 46510.3 46510.5 46589.8 3615.8 3750.3 3772.5 3880.7 4149.1 33565.8 33597.5 33631.0 33752.7 34026.0 4292.1 4363.3 4394.8 4404.2 4447.1 358653.0 360168.2 360701.0 361098.0 361510.0 37141.3 62222.7 62231.4 62355.0 62373.1 39252.8 39384.2 39406.6 39407.8 39421.0 17373.5 17480.4 17483.2 17484.8 17515.6 13254.7 14667.2 14761.7 14762.8 14777.3 8896.9 9239.1 9449.2 9452.9 9462.9 3630.3 3658.6 3662.2 3670.7 3672.6 1043.5 1099.0 1127.7 1164.8 1178.3 34504.8 34663.0 34796.1 34932.0 34955.5 1162.2 1167.7 1180.7 1231.3 1245.5 17815.8 18303.3 18403.4 18420.9 18446.8 585664.0 597125.0 599019.0 599051.0 599460.0 61341.4 61547.4 62079.7 62109.7 62198.0 1288.6 1303.4 1337.7 1413.5 1438.8 29988.9 35067.8 35079.3 35532.6 35566.9 52649.7 53235.7 53255.7 53266.7 53297.2 400532.0 402140.0 404766.0 405312.0 405552.0 30110.5 30756.8 30784.7 30930.0 32650.0 49997.0 50238.2 51120.0 51206.2 51269.5 42243.2 42477.0 43038.6 43993.0 44615.0 41219.4 41438.8 41712.0 41754.2 43362.6 41436.8 41972.0 42051.6 42104.2 42925.4 36259.0 38338.0 38372.6 38929.2 39126.0 29209.7 29220.6 29222.7 29421.9 29565.0 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#2] jquery.html Cycles:180 Average:29906.59 Median:11664.70 stddev:85496.75 (733.0%) stddev-sans-first:85707.88 03:45:35 INFO - PROCESS | 19927 | Values: 397.7 408.4 416.5 419.6 430.3 14302.2 16249.2 19890.2 20659.5 20986.9 19745.7 19783.3 20333.2 20679.2 20930.2 5786.7 6008.9 6022.3 6062.0 6238.0 4164.8 6036.6 6075.1 6076.1 6076.2 4361.5 4370.6 4406.3 6842.3 6890.1 6640.5 6700.6 6898.0 6906.5 6911.1 6000.8 6005.4 6014.3 6014.3 6026.2 14410.6 14654.6 14868.5 14910.8 15004.8 2378.1 2379.1 2621.9 2699.1 2888.6 10645.2 11423.0 13550.2 15530.1 15896.8 1946.1 1983.5 2012.5 2046.6 2062.4 71048.6 82909.2 85474.0 86271.1 87992.6 9603.6 10056.2 10119.1 10315.9 11729.6 14525.5 14564.3 14616.2 14631.9 14645.6 8592.0 8811.6 8931.2 8976.7 9007.2 9074.8 9077.7 9094.4 9287.5 9319.1 4442.2 4470.4 4545.0 4552.7 4598.6 2028.4 2101.2 2104.5 2134.4 2186.7 882.8 967.5 996.9 1042.8 1056.9 19068.7 20597.8 20691.5 21195.0 21636.1 1037.9 1047.1 1047.6 1084.1 1112.6 11193.9 11533.4 11535.0 11827.3 11892.5 493476.0 495572.0 498390.0 542713.0 553149.0 98785.0 102976.5 106566.0 106934.5 109591.0 1086.2 1120.3 1131.7 1167.3 1172.7 13717.4 17548.7 18394.4 18524.0 18593.7 30352.5 37851.6 37855.0 42898.8 43665.8 56728.7 57524.4 58287.4 58539.7 60043.4 8362.0 8448.1 8569.0 8633.3 8684.6 11899.4 12165.3 12172.1 12522.2 12569.9 10452.7 10560.9 10648.9 10697.6 10953.5 10378.5 10429.6 10494.4 10640.9 10756.4 10516.3 10552.3 10764.5 10783.5 10855.2 8121.7 8271.0 8448.7 8487.0 8714.7 9158.6 10209.4 10406.3 10534.2 10599.5 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#3] mootools.html Cycles:180 Average:5995.36 Median:4865.72 stddev:3420.25 (70.3%) stddev-sans-first:3413.02 03:45:35 INFO - PROCESS | 19927 | Values: 1481.3 1518.5 1637.9 1723.6 1794.2 8531.2 9304.6 9310.2 9311.0 9315.9 8078.5 8295.6 8607.9 8801.7 9126.6 2455.4 2482.9 2483.1 2485.4 2485.8 2386.5 2520.6 2521.0 2521.7 2523.6 2435.8 2444.5 2509.4 2513.5 2513.7 2424.9 2438.1 2448.8 2452.7 2454.7 2410.6 2422.9 2430.9 2431.4 2467.6 9104.4 9139.8 9209.4 9218.7 9221.1 5440.8 5572.2 5574.1 5576.3 5577.7 3211.7 3304.9 3350.5 3360.8 3365.6 1222.2 1223.6 1231.3 1255.3 1268.2 12631.9 12677.6 12711.1 12727.4 12740.7 4803.5 4810.9 4821.9 4909.6 5015.8 10824.8 10829.4 10832.7 10834.2 10841.6 5512.4 5558.7 5565.1 5566.4 5569.0 4411.8 4552.7 4982.5 5088.1 5127.0 4210.6 4264.4 4379.7 4419.5 4448.7 1411.1 1448.4 1456.8 1457.8 1463.1 848.6 910.7 919.2 928.3 958.7 5823.8 5920.1 5929.4 5983.6 6344.3 936.4 948.7 965.0 987.9 1013.9 3821.2 3931.2 4032.2 4112.2 4298.6 11371.7 11616.9 11644.3 11645.9 11668.1 11455.4 11536.3 11536.4 11539.2 11615.6 8012.2 8254.9 8359.3 8571.3 8576.9 5901.1 5939.3 6080.5 6090.8 6119.4 10180.9 10541.2 10565.9 10568.8 10670.2 10677.5 10729.5 10755.3 10759.4 10762.9 7155.9 7401.0 7847.7 8081.8 8229.1 8461.6 8948.8 8960.2 8963.4 8979.2 7731.7 7896.5 7951.2 7977.2 7986.0 7514.7 7758.1 7781.6 7790.0 7799.3 6899.5 6941.1 6956.2 6957.7 6960.0 4973.7 5127.1 5132.8 5175.9 5350.7 5136.5 5182.8 5232.4 5350.1 5372.1 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#4] prototype.html Cycles:180 Average:4903.32 Median:5614.70 stddev:1465.71 (26.1%) stddev-sans-first:1428.92 03:45:35 INFO - PROCESS | 19927 | Values: 322.3 334.2 335.1 338.7 342.0 5481.9 5527.3 5565.5 5590.6 5604.8 5513.5 5523.7 5530.7 5535.5 5547.9 4313.3 4319.6 4338.2 4345.2 4351.3 4016.9 4087.4 4249.2 4261.2 4356.8 4305.0 4308.2 4310.4 4317.3 4341.7 4300.3 4311.0 4314.3 4340.7 4347.8 3561.6 3650.3 3786.8 3820.2 3861.8 5385.2 5393.1 5393.6 5396.4 5413.0 4853.6 4934.2 4952.0 4955.5 4957.8 5624.6 5633.7 5667.0 5675.2 5675.6 4128.2 4135.7 4147.8 4152.7 4198.6 5951.9 5979.6 5987.4 6020.1 6023.3 3995.3 4188.1 4220.5 4229.2 4243.5 4272.6 4277.9 4281.7 4282.6 4284.9 4879.6 4891.1 4909.6 4913.8 4915.1 5563.5 5824.0 5865.7 5870.6 5872.2 1465.5 1477.0 1480.1 1482.4 1484.4 904.8 920.4 947.4 948.2 952.8 4990.5 5029.3 5029.8 5035.8 5036.0 5755.6 5832.5 5896.4 6052.5 6058.4 5663.2 5696.5 5702.2 5736.6 5740.6 3080.9 3166.6 3177.5 3201.0 3222.5 6246.2 6252.7 6260.6 6263.7 6281.7 5839.9 6175.0 6215.6 6234.5 6236.0 6200.8 6219.2 6230.3 6245.4 6245.8 6046.9 6080.4 6080.7 6087.7 6096.1 5844.9 5957.4 5966.7 5977.1 5996.2 6195.5 6235.8 6253.0 6262.4 6273.9 4285.8 4324.6 4377.1 4382.3 4384.3 6042.3 6058.2 6062.7 6067.1 6095.8 5909.7 5916.6 5939.4 5953.0 5955.1 5697.5 5809.5 5843.8 5875.4 5939.4 5874.4 5884.7 5890.4 5894.2 5908.9 5759.9 5906.0 5943.5 5962.6 5968.4 5774.4 5786.8 5805.8 5805.8 5815.5 03:45:35 INFO - PROCESS | 19927 | 03:45:35 INFO - PROCESS | 19927 | [#5] yui.html Cycles:180 Average:22229.69 Median:4068.38 stddev:32623.91 (801.9%) stddev-sans-first:32675.73 03:45:35 INFO - PROCESS | 19927 | Values: 793.1 812.3 830.0 857.4 859.9 15989.0 16885.8 17297.5 17462.4 17474.1 14846.9 14852.5 14984.0 15007.4 15017.3 858.1 869.1 878.9 896.2 905.4 893.3 897.5 898.1 898.5 898.5 936.4 937.1 939.4 941.0 941.1 931.6 935.6 937.2 937.6 937.8 688.7 689.4 689.6 689.6 689.6 16851.2 16980.9 16987.4 17013.2 17021.7 15545.8 16152.9 16229.9 16719.4 16779.7 13097.7 13207.5 13505.1 13576.8 13580.7 3994.5 3995.8 4009.9 4126.9 4195.9 88425.0 91067.0 91942.5 92474.0 93024.5 10853.3 12034.5 12127.6 12198.3 12268.9 22272.3 22350.8 22451.2 22480.9 22550.0 8921.2 9200.8 9470.0 9552.3 9562.0 4747.3 4774.0 4777.3 4818.0 4921.4 3561.8 3658.6 3665.2 3672.2 3677.9 606.0 620.5 624.8 636.5 638.6 713.2 716.5 738.7 801.1 807.4 9716.5 10223.7 10483.2 10484.0 10673.4 749.5 769.3 789.5 792.7 834.4 4967.3 5141.7 5168.1 5251.4 5310.5 71655.0 93873.0 95869.0 131899.0 137800.3 106783.0 112012.0 113308.4 113484.6 113942.4 95781.0 95857.0 96501.5 97168.5 99601.0 9893.7 9947.9 9985.3 10035.9 10044.7 55085.2 56146.0 56947.0 57598.4 57787.7 74912.0 80089.2 87482.6 89512.0 90264.0 12843.9 12906.0 12906.8 12934.2 13235.7 13074.6 13509.3 13607.7 14041.2 14271.7 11824.6 12156.5 12522.9 12771.8 13163.0 10407.7 10751.6 10830.9 11020.8 11270.2 11946.2 12844.0 12888.4 13216.1 13285.7 10943.5 10984.8 11079.6 11362.5 11540.7 10210.2 10983.9 11202.9 11205.0 11315.6 03:45:35 INFO - PROCESS | 19927 | -------- Summary: end -------- 03:45:35 INFO - PROCESS | 19927 | 03:45:36 INFO - TEST-INFO | 19927: exit 0 03:45:36 INFO - TEST-OK | dromaeo_css | took 1113196ms 03:45:36 INFO - TEST-START | kraken 03:45:36 INFO - operating with platform_type : linux_ 03:45:36 INFO - Initialising browser for kraken test... 03:45:36 INFO - TEST-INFO | started process 20247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0wK5yf/profile http://localhost:38626/getInfo.html) 03:45:40 INFO - PROCESS | 20247 | __metrics Screen width/height:1600/1200 03:45:40 INFO - PROCESS | 20247 | colorDepth:24 03:45:40 INFO - PROCESS | 20247 | Browser inner width/height: 1024/697 03:45:40 INFO - PROCESS | 20247 | __metrics 03:45:40 INFO - PROCESS | 20247 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:45:40 INFO - PROCESS | 20247 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:45:41 INFO - PROCESS | 20247 | console.error: PushService: 03:45:41 INFO - PROCESS | 20247 | stateChangeProcessEnqueue: Error transitioning state 03:45:41 INFO - PROCESS | 20247 | UnknownError 03:45:41 INFO - TEST-INFO | 20247: exit 0 03:45:41 INFO - Browser initialized. 03:45:41 INFO - Running cycle 1/1 for kraken test... 03:45:41 INFO - TEST-INFO | started process 20338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0wK5yf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:53 INFO - PROCESS | 20338 | RSS: Main: 148721664 03:45:53 INFO - PROCESS | 20338 | 03:45:55 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38626/tests/kraken/driver.html?testName=audio-beat-detection) 03:45:56 INFO - PROCESS | 20338 | RSS: Main: 189739008 03:45:56 INFO - PROCESS | 20338 | 03:45:59 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38626/tests/kraken/driver.html?testName=audio-dft) 03:45:59 INFO - PROCESS | 20338 | RSS: Main: 179027968 03:45:59 INFO - PROCESS | 20338 | 03:46:02 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38626/tests/kraken/driver.html?testName=audio-fft) 03:46:03 INFO - PROCESS | 20338 | RSS: Main: 270446592 03:46:03 INFO - PROCESS | 20338 | 03:46:05 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38626/tests/kraken/driver.html?testName=audio-oscillator) 03:46:05 INFO - PROCESS | 20338 | RSS: Main: 176861184 03:46:05 INFO - PROCESS | 20338 | 03:46:07 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38626/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:46:08 INFO - PROCESS | 20338 | RSS: Main: 178642944 03:46:08 INFO - PROCESS | 20338 | 03:46:11 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38626/tests/kraken/driver.html?testName=imaging-darkroom) 03:46:12 INFO - PROCESS | 20338 | RSS: Main: 214728704 03:46:12 INFO - PROCESS | 20338 | 03:46:15 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38626/tests/kraken/driver.html?testName=imaging-desaturate) 03:46:15 INFO - PROCESS | 20338 | RSS: Main: 214052864 03:46:15 INFO - PROCESS | 20338 | 03:46:18 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38626/tests/kraken/driver.html?testName=json-parse-financial) 03:46:19 INFO - PROCESS | 20338 | RSS: Main: 213204992 03:46:19 INFO - PROCESS | 20338 | 03:46:20 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38626/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:46:21 INFO - PROCESS | 20338 | RSS: Main: 175300608 03:46:21 INFO - PROCESS | 20338 | 03:46:23 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:46:23 INFO - PROCESS | 20338 | RSS: Main: 183169024 03:46:23 INFO - PROCESS | 20338 | 03:46:25 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:46:26 INFO - PROCESS | 20338 | RSS: Main: 189505536 03:46:26 INFO - PROCESS | 20338 | 03:46:28 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:46:29 INFO - PROCESS | 20338 | RSS: Main: 197627904 03:46:29 INFO - PROCESS | 20338 | 03:46:32 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:46:32 INFO - PROCESS | 20338 | RSS: Main: 195682304 03:46:32 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | Cycle 1(1): loaded http://localhost:38626/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38626/tests/kraken/driver.html?testName=ai-astar) 03:46:34 INFO - PROCESS | 20338 | RSS: Main: 245800960 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | __start_tp_report 03:46:34 INFO - PROCESS | 20338 | _x_x_mozilla_page_load 03:46:34 INFO - PROCESS | 20338 | _x_x_mozilla_page_load_details 03:46:34 INFO - PROCESS | 20338 | |i|pagename|runs| 03:46:34 INFO - PROCESS | 20338 | |0;ai-astar;108;113;111;112;111;111;110;112;109;110 03:46:34 INFO - PROCESS | 20338 | |1;audio-beat-detection;145;115;121;152;119;121;121;122;144;121 03:46:34 INFO - PROCESS | 20338 | |2;audio-dft;161;161;161;161;160;160;162;165;160;160 03:46:34 INFO - PROCESS | 20338 | |3;audio-fft;75;76;75;110;76;75;77;75;108;76 03:46:34 INFO - PROCESS | 20338 | |4;audio-oscillator;81;82;122;82;81;81;100;81;81;106 03:46:34 INFO - PROCESS | 20338 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;116;118;117 03:46:34 INFO - PROCESS | 20338 | |6;imaging-darkroom;148;145;147;146;146;146;146;145;146;146 03:46:34 INFO - PROCESS | 20338 | |7;imaging-desaturate;94;95;95;94;95;95;96;95;94;95 03:46:34 INFO - PROCESS | 20338 | |8;json-parse-financial;51;48;50;49;48;50;48;49;49;50 03:46:34 INFO - PROCESS | 20338 | |9;json-stringify-tinderbox;60;59;59;61;60;59;59;60;60;60 03:46:34 INFO - PROCESS | 20338 | |10;stanford-crypto-aes;81;76;72;75;73;76;78;79;78;79 03:46:34 INFO - PROCESS | 20338 | |11;stanford-crypto-ccm;125;135;122;128;119;120;120;120;161;122 03:46:34 INFO - PROCESS | 20338 | |12;stanford-crypto-pbkdf2;153;154;153;152;152;151;154;152;154;159 03:46:34 INFO - PROCESS | 20338 | |13;stanford-crypto-sha256-iterative;64;63;61;62;62;62;61;64;61;62 03:46:34 INFO - PROCESS | 20338 | __end_tp_report 03:46:34 INFO - PROCESS | 20338 | __start_cc_report 03:46:34 INFO - PROCESS | 20338 | _x_x_mozilla_cycle_collect,1597 03:46:34 INFO - PROCESS | 20338 | __end_cc_report 03:46:34 INFO - PROCESS | 20338 | __startTimestamp1478691994534__endTimestamp 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | ------- Summary: start ------- 03:46:34 INFO - PROCESS | 20338 | Number of tests: 14 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:1.49 (1.3%) stddev-sans-first:1.22 03:46:34 INFO - PROCESS | 20338 | Values: 108.0 113.0 111.0 112.0 111.0 111.0 110.0 112.0 109.0 110.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:121.50 stddev:13.35 (11.0%) stddev-sans-first:12.68 03:46:34 INFO - PROCESS | 20338 | Values: 145.0 115.0 121.0 152.0 119.0 121.0 121.0 122.0 144.0 121.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.52 (0.9%) stddev-sans-first:1.62 03:46:34 INFO - PROCESS | 20338 | Values: 161.0 161.0 161.0 161.0 160.0 160.0 162.0 165.0 160.0 160.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#3] audio-fft Cycles:10 Average:82.30 Median:75.50 stddev:14.10 (18.7%) stddev-sans-first:14.70 03:46:34 INFO - PROCESS | 20338 | Values: 75.0 76.0 75.0 110.0 76.0 75.0 77.0 75.0 108.0 76.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:81.00 stddev:14.58 (18.0%) stddev-sans-first:15.12 03:46:34 INFO - PROCESS | 20338 | Values: 81.0 82.0 122.0 82.0 81.0 81.0 100.0 81.0 81.0 106.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 03:46:34 INFO - PROCESS | 20338 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 118.0 117.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.60 03:46:34 INFO - PROCESS | 20338 | Values: 148.0 145.0 147.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 03:46:34 INFO - PROCESS | 20338 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 96.0 95.0 94.0 95.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#8] json-parse-financial Cycles:10 Average:49.20 Median:49.50 stddev:1.03 (2.1%) stddev-sans-first:0.87 03:46:34 INFO - PROCESS | 20338 | Values: 51.0 48.0 50.0 49.0 48.0 50.0 48.0 49.0 49.0 50.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 03:46:34 INFO - PROCESS | 20338 | Values: 60.0 59.0 59.0 61.0 60.0 59.0 59.0 60.0 60.0 60.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:78.00 stddev:2.83 (3.6%) stddev-sans-first:2.54 03:46:34 INFO - PROCESS | 20338 | Values: 81.0 76.0 72.0 75.0 73.0 76.0 78.0 79.0 78.0 79.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:123.50 stddev:12.85 (10.4%) stddev-sans-first:13.60 03:46:34 INFO - PROCESS | 20338 | Values: 125.0 135.0 122.0 128.0 119.0 120.0 120.0 120.0 161.0 122.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.50 stddev:2.22 (1.4%) stddev-sans-first:2.35 03:46:34 INFO - PROCESS | 20338 | Values: 153.0 154.0 153.0 152.0 152.0 151.0 154.0 152.0 154.0 159.0 03:46:34 INFO - PROCESS | 20338 | 03:46:34 INFO - PROCESS | 20338 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.00 03:46:34 INFO - PROCESS | 20338 | Values: 64.0 63.0 61.0 62.0 62.0 62.0 61.0 64.0 61.0 62.0 03:46:34 INFO - PROCESS | 20338 | -------- Summary: end -------- 03:46:34 INFO - PROCESS | 20338 | 03:46:35 INFO - TEST-INFO | 20338: exit 0 03:46:35 INFO - TEST-OK | kraken | took 59486ms 03:46:35 INFO - SUITE-END | took 1172s 03:46:35 INFO - Completed test suite (00:19:32) 03:46:35 INFO - javascript benchmark 03:46:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3133.263683034487, 3169.6037995250595, 3173.9365212695734, 3199.040287913628, 3211.9839400802994, 5513.531349835263, 5580.8604784880345, 5586.162075688649, 5601.747921343539, 5619.522340601054, 5560.332760068787, 5614.466625670565, 5622.4939755422, 5630.971845140769, 5642.887142257163, 5021.422536408314, 5059.16523773577, 5102.489751024905, 5150.1759718445055, 5180.0, 5001.874953126165, 5108.66793658411, 5158.690478571279, 5164.586833053347, 5171.405288391827, 4963.950360496385, 5041.495850414947, 5071.746412679369, 5092.8472145835685, 5096.0, 5094.46508116648, 5216.66091704038, 5219.164933610642, 5224.242484839677, 5228.111221092423, 5032.521910418541, 5100.5409513143995, 5159.690418574897, 5175.534201921808, 5201.063808514466, 5384.6499977501335, 5430.321209848769, 5441.211024401479, 5457.781688732446, 5482.342118945712, 5875.412458754133, 5880.294364676266, 5886.411358864122, 5983.491403230731, 5986.580939334249, 6009.549283803733, 6024.09638554216, 6024.939750602482, 6028.09578563219, 6031.095335699636, 5448.91826622597, 5527.53015993641, 5574.60977731559, 5597.552195824324, 5612.85967850807, 6121.4490695837585, 6146.0, 6153.0, 6163.075538669235, 6171.537134714914, 5919.792807251725, 5926.496247818897, 5926.851828704317, 5935.376785437553, 5937.465628093492, 5768.942310576925, 5800.622959507618, 5921.703914804242, 5944.7027648617395, 5946.256717910262, 5948.256467941507, 5959.0, 5964.552658550626, 5981.102834574848, 5990.5507086968655, 5941.494972927349, 5991.910121348184, 5996.220491336186, 6011.579189456784, 6015.759369625166, 4479.238529450001, 4508.211063063951, 4565.383673204141, 4603.562661547147, 4645.814167433265, 4071.75569465833, 4096.692748043909, 4120.17596480705, 4124.298869192245, 4127.731697439657, 5736.311642602915, 5739.196512488255, 5739.684317362585, 5746.0, 5749.108888122348, 5815.76736930518, 5872.500837428781, 5941.376155503696, 6024.397560244028, 6026.90959635597, 5644.350899646511, 5669.433056694297, 5689.544836413077, 5714.942850571524, 5716.114002329646, 5162.8967420652125, 5170.819021334235, 5176.197689358156, 5181.326427564392, 5203.869903252374, 5900.586958912921, 5996.820095397145, 6081.0, 6119.2350956130485, 6139.877202456015, 5964.165016897725, 5983.461488466059, 6017.398260173947, 6060.181875446759, 6105.084237364431, 6198.101275315144, 6211.192544969125, 6223.813285601439, 6232.8130156095385, 6235.906461403173, 5749.310082790092, 5935.0503919372695, 5954.196183515343, 5956.344802071682, 5984.670843103497, 5827.79602713903, 5960.850978725566, 6016.548758843017, 6019.789307374221, 6090.878182436238, 5868.2077919482035, 5915.497182739516, 5920.023196172583, 5930.05119180929, 5931.970340148272, 5848.239728835224, 5861.3845546217035, 5866.032104702847, 5869.413058694096, 5873.647581145145, 5584.888302233851, 5666.206731057738, 5704.515116215002, 5752.913706294493, 5796.1305804129715, 5879.588428809942, 5880.617759845596, 5882.205902203319, 5887.264091988502, 5907.32065812437, 5548.0845660467185, 5723.627964182315, 5737.540996720169, 5747.19539264495, 5747.396523364986], "value": 5511.65481901394, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.9732712027958, 632.0266789144716, 632.8038308124479, 644.0532417346502, 644.8806970710417, 34484.0, 37438.812805936, 45324.0, 45381.7730911345, 45437.09125817482, 48780.75609621947, 49284.0, 49359.75320123394, 49381.50618493814, 49425.0, 9173.128552787475, 9307.860382094274, 9313.95343023284, 9342.0, 9357.906420935806, 8388.664453421887, 8578.485290882536, 10906.61826836061, 11134.777304453906, 11154.386508742016, 11332.660020199366, 11426.257293275952, 11435.714107147347, 11438.828417573743, 11440.0, 11158.832617510743, 11241.550337986471, 11255.606053788078, 11255.774884502263, 11261.4369281536, 11011.779764404668, 11058.170637202162, 11110.333379997226, 11117.22179447439, 11122.332660040423, 46126.0, 46459.0, 46510.30234546484, 46510.53489465096, 46589.76705116487, 3615.7830530168276, 3750.324941510527, 3772.4529943158363, 3880.689544836406, 4149.128682976579, 33565.83217083899, 33597.49603755945, 33631.0, 33752.662473375196, 34026.0, 4292.141571685675, 4363.345498175299, 4394.78026098697, 4404.163208990313, 4447.08834688888, 358653.0, 360168.1949541518, 360700.9999999974, 361098.0, 361510.0, 37141.257174856895, 62222.688886556185, 62231.377686223466, 62355.0, 62373.064404033976, 39252.803735981135, 39384.212315753524, 39406.60593394087, 39407.80296098559, 39421.0, 17373.47879563615, 17480.388186413413, 17483.213255403538, 17484.82515174832, 17515.64968700619, 13254.668633284053, 14667.193304368362, 14761.704765904622, 14762.778558321634, 14777.261136943325, 8896.911030889738, 9239.076092390842, 9449.196818270386, 9452.90547094534, 9462.858057129148, 3630.2739452109686, 3658.63413658632, 3662.157703728104, 3670.6696397324367, 3672.5592928848173, 1043.5199808088212, 1099.0, 1127.6617014895662, 1164.8369228308043, 1178.269472926791, 34504.82747586296, 34663.0, 34796.13009674779, 34932.0, 34955.475667865, 1162.1864694713734, 1167.6730515455686, 1180.6635108993896, 1231.2981600487635, 1245.5391505143155, 17815.821841781417, 18303.267869285344, 18403.447896563124, 18420.907895460437, 18446.81553184451, 585664.0, 597125.0, 599019.0, 599051.0, 599460.0, 61341.386586133565, 61547.38452615506, 62079.689601551705, 62109.689451552455, 62198.0, 1288.5876519513665, 1303.4004357995238, 1337.725766217923, 1413.54059930523, 1438.8273407190995, 29988.850055749583, 35067.82466087653, 35079.298414031065, 35532.644673553965, 35566.93299201028, 52649.736751316, 53235.73382133064, 53255.7337213327, 53266.73366633142, 53297.20054199112, 400532.0, 402140.0, 404766.0, 405312.0, 405552.0, 30110.548341774454, 30756.846215769674, 30784.692153078184, 30930.0, 32650.0, 49997.0, 50238.246426304366, 51120.0, 51206.23190652069, 51269.48730512647, 42243.1551368977, 42477.0, 43038.56961430471, 43993.0, 44615.0, 41219.38170927498, 41438.79280603577, 41712.0, 41754.16491670089, 43362.56637433711, 41436.792816035726, 41972.0, 42051.57948420477, 42104.1579168421, 42925.356119657605, 36259.0, 38338.0, 38372.61627383802, 38929.221415572094, 39126.0, 29209.707902920698, 29220.561691574218, 29222.707772922, 29421.85289073541, 29565.0], "value": 20371.71215998217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.72556935714374, 408.44451545897573, 416.5418040155829, 419.6307249620336, 430.26854347609054, 14302.213378264192, 16249.187540622981, 19890.20439182433, 20659.483512912186, 20986.895065524688, 19745.703814442793, 19783.307584234553, 20333.186672533117, 20679.172833086657, 20930.16279348828, 5786.652800831943, 6008.879822403538, 6022.277326720778, 6062.0, 6237.968810155943, 4164.791760411982, 6036.63780173191, 6075.058365953284, 6076.058210977306, 6076.240469941257, 4361.520232774408, 4370.56294370562, 4406.272964960777, 6842.349976752201, 6890.138732658418, 6640.535162538624, 6700.631465269408, 6897.965510172442, 6906.482013848957, 6911.101556797635, 6000.819975400745, 6005.39946005396, 6014.278286605635, 6014.30835453924, 6026.216591843032, 14410.567682969528, 14654.56036318912, 14868.479603213835, 14910.776338354824, 15004.849951500455, 2378.084437491561, 2379.07216185686, 2621.89512419503, 2699.068821256683, 2888.638920134983, 10645.20160987929, 11423.029042531394, 13550.186988780606, 15530.145841978687, 15896.76154857678, 1946.143696773415, 1983.5140390604395, 2012.4968757810548, 2046.6418376784004, 2062.391594479632, 71048.6447567764, 82909.17090829136, 85474.0, 86271.13728862756, 87992.56003720069, 9603.567839447242, 10056.245781566411, 10119.089281964658, 10315.896841031643, 11729.648110556696, 14525.491607793676, 14564.344604492824, 14616.196109213883, 14631.853681463263, 14645.560633181021, 8592.032967032992, 8811.559422028873, 8931.151540603632, 8976.730698079067, 9007.23438507721, 9074.773130671787, 9077.68228112013, 9094.363394562317, 9287.48918809472, 9319.068093190626, 4442.222611043055, 4470.418845550058, 4545.0000999779995, 4552.681312308173, 4598.609118224988, 2028.361155986331, 2101.1805395895612, 2104.537001859619, 2134.3916983659583, 2186.7157269554855, 882.82343531294, 967.4533888353058, 996.9202463802878, 1042.8227201375785, 1056.8943105689523, 19068.71396929047, 20597.794022059887, 20691.4827129323, 21195.0, 21636.134554617955, 1037.9117774989058, 1047.0994944347856, 1047.5757318286107, 1084.1110289562482, 1112.554978008803, 11193.94403027996, 11533.365664888344, 11534.98228270705, 11827.290362578273, 11892.464839082262, 493476.0, 495572.0, 498390.0, 542713.0, 553149.0, 98785.0, 102976.48511757543, 106566.0, 106934.46532767286, 109591.0, 1086.179934149712, 1120.3054106454051, 1131.7114135895458, 1167.3229526874393, 1172.6657902497748, 13717.382717777524, 17548.656508662574, 18394.448166555027, 18524.0, 18593.72109418333, 30352.54471182978, 37851.62148378481, 37855.0, 42898.78550607227, 43665.78167109144, 56728.71635641796, 57524.42475575358, 58287.417125829896, 58539.70730146322, 60043.39956600378, 8362.0, 8448.112948140602, 8568.957155214184, 8633.30933525304, 8684.565771711515, 11899.405029748652, 12165.330906799856, 12172.14794964344, 12522.248665080124, 12569.93715031419, 10452.68641940743, 10560.947195263974, 10648.89351106479, 10697.572097116028, 10953.507092180711, 10378.481075946022, 10429.63496277657, 10494.370337779757, 10640.946795265974, 10756.354618722902, 10516.316439431412, 10552.261341706007, 10764.46177691128, 10783.51474183648, 10855.185861060294, 8121.675132994614, 8270.958645206976, 8448.746537603882, 8487.0, 8714.738557843275, 9158.633654653739, 10209.438480883622, 10406.323588966692, 10534.157267418435, 10599.523021464207], "value": 9311.866289408566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.281578434459, 1518.5140754958413, 1637.9262933168013, 1723.6208034232463, 1794.165712943481, 8531.232189102979, 9304.627814887397, 9310.162085412303, 9311.0, 9315.860262096074, 8078.515289082645, 8295.585220738969, 8607.91392086079, 8801.691940782073, 9126.589303481343, 2455.4229756007326, 2482.900683972648, 2483.1433155561394, 2485.403503159247, 2485.801135909132, 2386.546556154325, 2520.621906713998, 2521.004203339689, 2521.735217802132, 2523.621456781479, 2435.768601982809, 2444.535538247736, 2509.4228327484598, 2513.49730053989, 2513.736057713941, 2424.9393765155924, 2438.0979037756056, 2448.8285819992607, 2452.7056753189554, 2454.6686197363483, 2410.6022506286617, 2422.91519796808, 2430.902763889442, 2431.4043059450546, 2467.629855521668, 9104.36269461138, 9139.771505712344, 9209.355345125843, 9218.677346292914, 9221.077892210726, 5440.809571664972, 5572.164175373727, 5574.052411090124, 5576.302962129734, 5577.749001294951, 3211.694888985566, 3304.9008529744146, 3350.530925670408, 3360.83195840207, 3365.612954510238, 1222.1689251309106, 1223.608445297505, 1231.34123244064, 1255.3095797311441, 1268.226381907036, 12631.873681263161, 12677.619671409873, 12711.110222284444, 12727.42726577297, 12740.681482962907, 4803.519648035239, 4810.855674329776, 4821.855344339676, 4909.582685471775, 5015.774290156952, 10824.783504330027, 10829.404382759023, 10832.67501974942, 10834.241603087867, 10841.620543280947, 5512.39363669996, 5558.722063896789, 5565.1095824667855, 5566.35986861508, 5569.0, 4411.757353345597, 4552.658550608718, 4982.526659967298, 5088.1350170471105, 5127.0, 4210.59999300066, 4264.402983582301, 4379.715318504287, 4419.513853476115, 4448.666350023761, 1411.1110000699698, 1448.4061534770694, 1456.7814827775705, 1457.8177727784027, 1463.0782606957548, 848.6393282854762, 910.6721580231111, 919.1727445299284, 928.2991341537096, 958.7411398922252, 5823.767049317979, 5920.141579470953, 5929.407059294036, 5983.640981541122, 6344.270408902943, 936.3679516326453, 948.7201275623707, 964.9710508684751, 987.9012098790064, 1013.9442330671884, 3821.178446633987, 3931.2334094851803, 4032.213718324957, 4112.157007813391, 4298.634616057608, 11371.658850234506, 11616.94191529037, 11644.301341919512, 11645.88354116448, 11668.124890633237, 11455.369954652408, 11536.250143740797, 11536.423178841025, 11539.249948753304, 11615.59345422923, 8012.238837310446, 8254.917450825415, 8359.289460395812, 8571.271441927382, 8576.871346929676, 5901.1443340714495, 5939.287285525592, 6080.452759251689, 6090.786822461193, 6119.35746746603, 10180.898191018292, 10541.209409294486, 10565.894341056492, 10568.788624227625, 10670.199735019749, 10677.51951162184, 10729.46352682347, 10755.300905441121, 10759.408232547285, 10762.892371076188, 7155.892661610184, 7401.0, 7847.725329613436, 8081.797955051171, 8229.05365882931, 8461.61922713467, 8948.776280593038, 8960.238379737795, 8963.417377870417, 8979.191872731486, 7731.729389471341, 7896.526208427513, 7951.204879512234, 7977.162397948132, 7986.0, 7514.736984205527, 7758.107817600822, 7781.572013539309, 7789.961050194939, 7799.337056350047, 6899.517033807585, 6941.097657304518, 6956.165260168812, 6957.686904089227, 6959.965200173967, 4973.701577905337, 5127.077126117335, 5132.79468821243, 5175.870603234798, 5350.652207606493, 5136.460671629425, 5182.844514664566, 5232.372115346183, 5350.063738845609, 5372.059889519241], "value": 4808.787216330591, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3327711636911, 334.22293168383504, 335.138693557557, 338.73578608685204, 342.0320493004797, 5481.917771233429, 5527.314169178399, 5565.526930210937, 5590.552755779538, 5604.794969081647, 5513.531349835269, 5523.696196709179, 5530.7323997161075, 5535.481355983028, 5547.889042219153, 4313.309870420733, 4319.611234988851, 4338.2191205582985, 4345.217860785057, 4351.303791393378, 4016.919661606766, 4087.386891966199, 4249.150169966019, 4261.190373828978, 4356.80394382252, 4304.97847510762, 4308.230298488822, 4310.353446982962, 4317.287647538153, 4341.7177883437635, 4300.268954277781, 4310.978445107786, 4314.288142456491, 4340.696151269412, 4347.826086956518, 3561.626029266916, 3650.34293827111, 3786.8106594670157, 3820.2168555446065, 3861.8455261789495, 5385.21914322425, 5393.1370980642905, 5393.649412788156, 5396.3524377075, 5412.972935135299, 4853.563179313879, 4934.210526315808, 4951.975240123812, 4955.5044495550155, 4957.769003948238, 5624.606277560572, 5633.6619802811965, 5666.971665141689, 5675.233843431125, 5675.63108397957, 4128.174365126986, 4135.6898232632675, 4147.751134931914, 4152.667786577066, 4198.580141985807, 5951.9107213391835, 5979.611325263887, 5987.43119403656, 6020.096985452217, 6023.337432882379, 3995.260876737813, 4188.120494696087, 4220.493540775126, 4229.154169166179, 4243.5119961204855, 4272.636825869774, 4277.898523570449, 4281.657467402601, 4282.593153650399, 4284.928767808048, 4879.585235254972, 4891.144049791273, 4909.631777616693, 4913.828016019422, 4915.066137433876, 5563.5271001964475, 5824.0, 5865.736041878125, 5870.618409803435, 5872.17789509469, 1465.5310300703673, 1476.9587440854173, 1480.1267252320968, 1482.3922191901484, 1484.4210757804465, 904.8009437923646, 920.4490433619372, 947.4220725357526, 948.1656142594611, 952.8189643967625, 4990.452959582541, 5029.270755740397, 5029.798808047637, 5035.84892453227, 5035.974820125876, 5755.568332375088, 5832.475077243069, 5896.380880007623, 6052.485538729169, 6058.4244496772735, 5663.235463212496, 5696.544276457874, 5702.173184888251, 5736.569757268138, 5740.56945729072, 3080.86136123875, 3166.6358368787883, 3177.4757165067967, 3200.9839950800097, 3222.5327327537843, 6246.219222597176, 6252.656103914328, 6260.5617606768, 6263.686815659199, 6281.7173227204885, 5839.9124013139835, 6175.023992322504, 6215.627062376272, 6234.501239900797, 6236.0, 6200.751969921243, 6219.191505104308, 6230.252369715572, 6245.406686364788, 6245.812625621239, 6046.939530604637, 6080.422359875804, 6080.69596520181, 6087.695615219132, 6096.1465394845645, 5844.912326315024, 5957.4042595740075, 5966.701664916824, 5977.1034344846885, 5996.190514280516, 6195.504359651127, 6235.844103897439, 6253.0, 6262.436380725574, 6273.905891411541, 4285.785710714389, 4324.5675432457765, 4377.146456440963, 4382.298832186835, 4384.2765943620225, 6042.305134909366, 6058.242719660043, 6062.696865156637, 6067.088867006879, 6095.786647467317, 5909.734061967136, 5916.645001299764, 5939.4357536034, 5952.970235148797, 5955.052249136705, 5697.544196464356, 5809.5352371811, 5843.766249349978, 5875.412458754261, 5939.3963629818845, 5874.353821079592, 5884.735186916513, 5890.440408161217, 5894.204282421818, 5908.852278693067, 5759.884802303846, 5905.970470147622, 5943.494802941798, 5962.612430192024, 5968.373320801254, 5774.39368866263, 5786.797462088806, 5805.767769289181, 5805.82582522525, 5815.476607105381], "value": 4428.345538425518, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.0919097633214, 812.2730156509924, 829.9626516806743, 857.3569822633024, 859.896812382514, 15989.04065756054, 16885.831141688595, 17297.48107556772, 17462.388816391427, 17474.126293685298, 14846.851531484683, 14852.48016319429, 14984.0, 15007.399704011827, 15017.324220410108, 858.1204265627734, 869.070094998352, 878.9340799440035, 896.2337201692557, 905.3979103895902, 893.3076865429312, 897.5108565831638, 898.0525545549443, 898.4788822482977, 898.510311880027, 936.419419959626, 937.1331518345503, 939.4175611121115, 940.9764755881089, 941.0730430525927, 931.6087243357772, 935.5696379665328, 937.1986951156765, 937.6483818568037, 937.7655586103474, 688.6763221285987, 689.4002218070311, 689.5896941319957, 689.6034780001519, 689.6310473896491, 16851.157442127846, 16980.915095424443, 16987.40544080951, 17013.23440445183, 17021.744673829904, 15545.766813497807, 16152.919235403866, 16229.918850405673, 16719.414820481346, 16779.748303775454, 13097.738045239043, 13207.537736179187, 13505.05464617477, 13576.796348054788, 13580.728385432236, 3994.460747799039, 3995.7802320872343, 4009.8596549120934, 4126.896827579335, 4195.895102622429, 88425.0, 91066.99999999933, 91942.5402872988, 92474.0, 93024.53487732586, 10853.34879907208, 12034.518619255306, 12127.575534856414, 12198.268103913617, 12268.938655306845, 22272.3318300448, 22350.77649223487, 22451.21420750266, 22480.88759556192, 22550.0, 8921.241694455914, 9200.815983680423, 9470.0, 9552.331336806357, 9561.952190239004, 4747.264174053027, 4773.9761301193275, 4777.331173635694, 4817.9518204818205, 4921.409430868247, 3561.7684850484634, 3658.634136586373, 3665.248624032067, 3672.2288319452678, 3677.9264414711556, 605.9787907423218, 620.4571000374672, 624.8312955501989, 636.4558302651259, 638.6040654794058, 713.2083387439937, 716.4912911832553, 738.7082102569454, 801.1027649033003, 807.4226927746689, 9716.514174291256, 10223.693289201336, 10483.213758968108, 10484.004019618276, 10673.412962286993, 749.4716225061319, 769.2615089514129, 789.5105034878412, 792.6512334572885, 834.3950635789125, 4967.254911763264, 5141.665791723526, 5168.1497284523275, 5251.396089449762, 5310.522053015247, 71655.0, 93873.0, 95869.0, 131899.0, 137800.31099844436, 106783.0, 112012.0, 113308.43345783219, 113484.59583928809, 113942.43028784968, 95781.0, 95857.0, 96501.51749241349, 97168.51415742876, 99601.00398996062, 9893.703188904345, 9947.86599815405, 9985.301028927905, 10035.949820250851, 10044.698659040241, 55085.173722393396, 56146.0, 56947.0, 57598.42401575931, 57787.711061444425, 74912.0, 80089.1991080105, 87482.56258718665, 89512.0, 90264.0, 12843.871561284268, 12906.0, 12906.773405725635, 12934.159279646792, 13235.735285294433, 13074.607761767162, 13509.324533773468, 13607.659808504866, 14041.227732474812, 14271.71456570842, 11824.58613948542, 12156.452959616661, 12522.937385313015, 12771.80842287385, 13163.0, 10407.68776936693, 10751.6236931707, 10830.945845270724, 11020.779584408107, 11270.211085223955, 11946.223495472766, 12844.0, 12888.355582221038, 13216.074874758675, 13285.734285314047, 10943.45282735876, 10984.780304394028, 11079.612213572485, 11362.54549818031, 11540.653780386601, 10210.234232432449, 10983.945080274547, 11202.887971120184, 11205.0, 11315.603953861575], "value": 7946.095388011865, "unit": "score"}], "name": "dromaeo_css", "value": 7492.668512591436, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 111.0, 112.0, 111.0, 111.0, 110.0, 112.0, 109.0, 110.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 121.0, 152.0, 119.0, 121.0, 121.0, 122.0, 144.0, 121.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 162.0, 165.0, 160.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 110.0, 76.0, 75.0, 77.0, 75.0, 108.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 122.0, 82.0, 81.0, 81.0, 100.0, 81.0, 81.0, 106.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 50.0, 49.0, 48.0, 50.0, 48.0, 49.0, 49.0, 50.0], "value": 49.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 61.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 72.0, 75.0, 73.0, 76.0, 78.0, 79.0, 78.0, 79.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 135.0, 122.0, 128.0, 119.0, 120.0, 120.0, 120.0, 161.0, 122.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 153.0, 152.0, 152.0, 151.0, 154.0, 152.0, 154.0, 159.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 61.0, 62.0, 62.0, 62.0, 61.0, 64.0, 61.0, 62.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1458.1000000000001, "alertThreshold": 2.0}]} 03:46:35 INFO - Return code: 0 03:46:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:46:35 INFO - # TBPL SUCCESS # 03:46:35 INFO - Running post-action listener: _resource_record_post_action 03:46:35 INFO - [mozharness: 2016-11-09 11:46:35.826785Z] Finished run-tests step (success) 03:46:35 INFO - Running post-run listener: _resource_record_post_run 03:46:35 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 359489536; Read time: 420; Write time: 238076 03:46:35 INFO - TinderboxPrint: CPU usage
12.9% 03:46:35 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 420 03:46:35 INFO - TinderboxPrint: I/O write bytes / time
359,489,536 / 238,076 03:46:35 INFO - TinderboxPrint: CPU idle
8,268.7 (87.0%) 03:46:35 INFO - TinderboxPrint: CPU user
1,184.0 (12.5%) 03:46:35 INFO - TinderboxPrint: Swap in / out
0 / 0 03:46:35 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107552768; Read time: 0; Write time: 98012 03:46:35 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 2039808; Write bytes: 249360384; Read time: 192; Write time: 139720 03:46:36 INFO - Running post-run listener: _upload_blobber_files 03:46:36 INFO - Blob upload gear active. 03:46:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:46:36 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 03:46:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:46:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:46:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:46:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:46:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:42 INFO - (blobuploader) - INFO - Done attempting. 03:46:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:46:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:46:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:43 INFO - (blobuploader) - INFO - Done attempting. 03:46:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:46:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:46:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:46:44 INFO - (blobuploader) - INFO - Done attempting. 03:46:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:46:44 INFO - Return code: 0 03:46:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:46:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:46:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0db027f7ab4feae72df63eda6f54f0309e66fef08ab7095c16104f66ea1b171f4a6c01fdb8b4b9a4bd9d21123ff13aa3d45b5c95ba620aee1ce558725340f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4776e8d5c511077bb83a4c20944da864b30a3cecd90e7946cb660a73dd619d21299b3fa5af697bd9d5856a1645a46b5bb85fd940a1558f760465875642235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d28b49d6cf07fbc52b7220a7ae1403a9677daf17fc7a8391afd692b9951e5779d702cc9b92103d251177646ee284e0d3410cb9f2721af433612a1356e5ee5f5f"} 03:46:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:46:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:46:44 INFO - Contents: 03:46:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0db027f7ab4feae72df63eda6f54f0309e66fef08ab7095c16104f66ea1b171f4a6c01fdb8b4b9a4bd9d21123ff13aa3d45b5c95ba620aee1ce558725340f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4776e8d5c511077bb83a4c20944da864b30a3cecd90e7946cb660a73dd619d21299b3fa5af697bd9d5856a1645a46b5bb85fd940a1558f760465875642235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d28b49d6cf07fbc52b7220a7ae1403a9677daf17fc7a8391afd692b9951e5779d702cc9b92103d251177646ee284e0d3410cb9f2721af433612a1356e5ee5f5f"} 03:46:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:46:44 INFO - Copying logs to upload dir... 03:46:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:46:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.376123 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-09 03:46:44.137949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 03:46:44.141254) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0db027f7ab4feae72df63eda6f54f0309e66fef08ab7095c16104f66ea1b171f4a6c01fdb8b4b9a4bd9d21123ff13aa3d45b5c95ba620aee1ce558725340f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4776e8d5c511077bb83a4c20944da864b30a3cecd90e7946cb660a73dd619d21299b3fa5af697bd9d5856a1645a46b5bb85fd940a1558f760465875642235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d28b49d6cf07fbc52b7220a7ae1403a9677daf17fc7a8391afd692b9951e5779d702cc9b92103d251177646ee284e0d3410cb9f2721af433612a1356e5ee5f5f"} build_url:https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba0db027f7ab4feae72df63eda6f54f0309e66fef08ab7095c16104f66ea1b171f4a6c01fdb8b4b9a4bd9d21123ff13aa3d45b5c95ba620aee1ce558725340f0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b4776e8d5c511077bb83a4c20944da864b30a3cecd90e7946cb660a73dd619d21299b3fa5af697bd9d5856a1645a46b5bb85fd940a1558f760465875642235b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d28b49d6cf07fbc52b7220a7ae1403a9677daf17fc7a8391afd692b9951e5779d702cc9b92103d251177646ee284e0d3410cb9f2721af433612a1356e5ee5f5f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-09 03:46:44.167631) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:46:44.167975) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1478690766.960095-816188765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-09 03:46:44.218189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 03:46:44.218515) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-09 03:46:44.218802) ========= ========= Total master_lag: 0.29 =========