builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1481044191.6 results: success (0) buildid: 20161206073109 builduid: 7bf6dd36e42a48d2879a10555311d99c revision: deacac546efaedc8b7a5eef776bf9bba8398d401 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.603807) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.604254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.604525) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.619794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.620124) ========= 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-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-06 09:09:51-- 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% 223M=0s 2016-12-06 09:09:51 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115385 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.748991) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.749312) ========= 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-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015234 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.798590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:51.798930) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev deacac546efaedc8b7a5eef776bf9bba8398d401 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev deacac546efaedc8b7a5eef776bf9bba8398d401 --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-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-06 09:09:51,907 truncating revision to first 12 chars 2016-12-06 09:09:51,907 Setting DEBUG logging. 2016-12-06 09:09:51,907 attempt 1/10 2016-12-06 09:09:51,908 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/deacac546efa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 09:09:52,210 unpacking tar archive at: mozilla-central-deacac546efa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659241 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:52.471976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:52.472278) ========= script_repo_revision: deacac546efaedc8b7a5eef776bf9bba8398d401 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:52.472660) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:52.472939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 09:09:52.487816) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-06 09:09:52.488134) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:09:52 INFO - MultiFileLogger online at 20161206 09:09:52 in /builds/slave/test 09:09:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:09:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:52 INFO - {'append_to_log': False, 09:09:52 INFO - 'base_work_dir': '/builds/slave/test', 09:09:52 INFO - 'blob_upload_branch': 'Firefox', 09:09:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:52 INFO - 'branch': 'Firefox', 09:09:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:52 INFO - 'config_files': ('talos/linux_config.py',), 09:09:52 INFO - 'default_actions': ('clobber', 09:09:52 INFO - 'read-buildbot-config', 09:09:52 INFO - 'download-and-extract', 09:09:52 INFO - 'populate-webroot', 09:09:52 INFO - 'create-virtualenv', 09:09:52 INFO - 'install', 09:09:52 INFO - 'run-tests'), 09:09:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:52 INFO - 'download_minidump_stackwalk': True, 09:09:52 INFO - 'download_symbols': 'ondemand', 09:09:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:52 INFO - 'installer_path': 'installer.exe', 09:09:52 INFO - 'log_level': 'info', 09:09:52 INFO - 'log_name': 'talos', 09:09:52 INFO - 'log_to_console': True, 09:09:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:09:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:09:52 INFO - 'opt_config_files': (), 09:09:52 INFO - 'pip_index': False, 09:09:52 INFO - 'sps_profile': False, 09:09:52 INFO - 'sps_profile_interval': 0, 09:09:52 INFO - 'suite': 'dromaeojs', 09:09:52 INFO - 'system_bits': '32', 09:09:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:52 INFO - 'title': 'talos-linux64-ix-047', 09:09:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:52 INFO - 'use_talos_json': True, 09:09:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:52 INFO - 'work_dir': 'build'} 09:09:52 INFO - [mozharness: 2016-12-06 17:09:52.696042Z] Running clobber step. 09:09:52 INFO - Running pre-action listener: _resource_record_pre_action 09:09:52 INFO - Running main action method: clobber 09:09:52 INFO - rmtree: /builds/slave/test/build 09:09:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:53 INFO - Running post-action listener: _resource_record_post_action 09:09:53 INFO - [mozharness: 2016-12-06 17:09:53.150024Z] Finished clobber step (success) 09:09:53 INFO - [mozharness: 2016-12-06 17:09:53.150124Z] Running read-buildbot-config step. 09:09:53 INFO - Running pre-action listener: _resource_record_pre_action 09:09:53 INFO - Running main action method: read_buildbot_config 09:09:53 INFO - Using buildbot properties: 09:09:53 INFO - { 09:09:53 INFO - "project": "", 09:09:53 INFO - "product": "firefox", 09:09:53 INFO - "script_repo_revision": "production", 09:09:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:09:53 INFO - "repository": "", 09:09:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:09:53 INFO - "buildid": "20161206073109", 09:09:53 INFO - "pgo_build": "True", 09:09:53 INFO - "basedir": "/builds/slave/test", 09:09:53 INFO - "buildnumber": 122, 09:09:53 INFO - "slavename": "talos-linux64-ix-047", 09:09:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:09:53 INFO - "platform": "ubuntu64_hw", 09:09:53 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:09:53 INFO - "branch": "mozilla-central", 09:09:53 INFO - "repo_path": "mozilla-central", 09:09:53 INFO - "revision": "deacac546efaedc8b7a5eef776bf9bba8398d401", 09:09:53 INFO - "stage_platform": "linux64-pgo", 09:09:53 INFO - "builduid": "7bf6dd36e42a48d2879a10555311d99c", 09:09:53 INFO - "slavebuilddir": "test" 09:09:53 INFO - } 09:09:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:09:53 INFO - Running post-action listener: _resource_record_post_action 09:09:53 INFO - [mozharness: 2016-12-06 17:09:53.152603Z] Finished read-buildbot-config step (success) 09:09:53 INFO - [mozharness: 2016-12-06 17:09:53.152692Z] Running download-and-extract step. 09:09:53 INFO - Running pre-action listener: _resource_record_pre_action 09:09:53 INFO - Running main action method: download_and_extract 09:09:53 INFO - mkdir: /builds/slave/test/build/tests 09:09:53 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')]} 09:09:53 INFO - trying https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:09:53 INFO - Downloading https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:09:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:09:53 INFO - Downloaded 1361 bytes. 09:09:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:09:53 INFO - Using the following test package requirements: 09:09:53 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:09:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:09:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:09:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'jsshell-linux-x86_64.zip'], 09:09:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:09:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:09:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:09:53 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:09:53 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:09:53 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:09:53 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:09:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:09:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:09:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:09:53 INFO - Fetch https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:09:56 INFO - Expected file size: 21782065 09:09:56 INFO - Obtained file size: 21782065 09:10:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:10:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:10:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:10:00 INFO - Fetch https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:10:01 INFO - Expected file size: 21782065 09:10:01 INFO - Obtained file size: 21782065 09:10:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:10:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:10:05 INFO - Fetch https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:10:08 INFO - Expected file size: 14409734 09:10:08 INFO - Obtained file size: 14409734 09:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:08 INFO - trying https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:10:11 INFO - Downloaded 59831277 bytes. 09:10:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:11 INFO - mkdir: /builds/slave/test/properties 09:10:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:10:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:10:11 INFO - Contents: 09:10:11 INFO - build_url:https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:10:12 INFO - Running post-action listener: _resource_record_post_action 09:10:12 INFO - Running post-action listener: set_extra_try_arguments 09:10:12 INFO - [mozharness: 2016-12-06 17:10:12.028753Z] Finished download-and-extract step (success) 09:10:12 INFO - [mozharness: 2016-12-06 17:10:12.028979Z] Running populate-webroot step. 09:10:12 INFO - Running pre-action listener: _resource_record_pre_action 09:10:12 INFO - Running main action method: populate_webroot 09:10:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:10:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:10:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:10:12 INFO - '--tppagecycles', 09:10:12 INFO - '7'], 09:10:12 INFO - 'tests': ['tsvgm']}}, 09:10:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:10:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:10:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:10:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:10:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:10:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['damp', 'tps']}, 09:10:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'tests': ['damp', 'tps']}, 09:10:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['dromaeo_dom']}, 09:10:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:10:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['basic_compositor_video']}, 09:10:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:10:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['a11yr', 09:10:12 INFO - 'ts_paint', 09:10:12 INFO - 'tpaint', 09:10:12 INFO - 'sessionrestore', 09:10:12 INFO - 'sessionrestore_no_auto_restore', 09:10:12 INFO - 'tabpaint']}, 09:10:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['tsvgx', 09:10:12 INFO - 'tsvgr_opacity', 09:10:12 INFO - 'tart', 09:10:12 INFO - 'tscrollx', 09:10:12 INFO - 'cart', 09:10:12 INFO - 'tsvg_static']}, 09:10:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:10:12 INFO - 'tsvgr_opacity', 09:10:12 INFO - 'tart', 09:10:12 INFO - 'tscrollx', 09:10:12 INFO - 'cart', 09:10:12 INFO - 'tsvg_static']}, 09:10:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'talos_options': ['--disable-e10s'], 09:10:12 INFO - 'tests': ['tp5o']}, 09:10:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:10:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'talos_options': ['--disable-e10s', 09:10:12 INFO - '--xperf_path', 09:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:12 INFO - 'tests': ['tp5n']}, 09:10:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:10:12 INFO - 'talos_options': ['--xperf_path', 09:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:10:12 INFO - 'tests': ['tp5n']}}, 09:10:12 INFO - 'talos.zip': {'path': '', 09:10:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:10:12 INFO - Running post-action listener: _resource_record_post_action 09:10:12 INFO - [mozharness: 2016-12-06 17:10:12.045449Z] Finished populate-webroot step (success) 09:10:12 INFO - [mozharness: 2016-12-06 17:10:12.045537Z] Running create-virtualenv step. 09:10:12 INFO - Running pre-action listener: _resource_record_pre_action 09:10:12 INFO - Running main action method: create_virtualenv 09:10:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:10:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:10:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:10:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:10:12 INFO - Using real prefix '/usr' 09:10:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:10:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:10:14 INFO - Installing pip.................done. 09:10:14 INFO - Return code: 0 09:10:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:10:14 INFO - Reading from file tmpfile_stdout 09:10:14 INFO - Output received: 09:10:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:10:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:10:14 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')]} 09:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:14 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')]} 09:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:14 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:10:14 INFO - Using env: {'DISPLAY': ':0', 09:10:14 INFO - 'HOME': '/home/cltbld', 09:10:14 INFO - 'LANG': 'en_US.UTF-8', 09:10:14 INFO - 'LANGUAGE': 'en_US:en', 09:10:14 INFO - 'LOGNAME': 'cltbld', 09:10:14 INFO - 'MAIL': '/var/mail/cltbld', 09:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:14 INFO - 'MOZ_NO_REMOTE': '1', 09:10:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:14 INFO - 'NO_EM_RESTART': '1', 09:10:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:14 INFO - 'PWD': '/builds/slave/test', 09:10:14 INFO - 'SHELL': '/bin/bash', 09:10:14 INFO - 'SHLVL': '1', 09:10:14 INFO - 'TERM': 'linux', 09:10:14 INFO - 'TMOUT': '86400', 09:10:14 INFO - 'USER': 'cltbld', 09:10:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:14 INFO - '_': '/tools/buildbot/bin/python'} 09:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:10:15 INFO - Cleaning up... 09:10:15 INFO - Return code: 0 09:10:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:15 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:15 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 09:10:15 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 09:10:15 INFO - Using env: {'DISPLAY': ':0', 09:10:15 INFO - 'HOME': '/home/cltbld', 09:10:15 INFO - 'LANG': 'en_US.UTF-8', 09:10:15 INFO - 'LANGUAGE': 'en_US:en', 09:10:15 INFO - 'LOGNAME': 'cltbld', 09:10:15 INFO - 'MAIL': '/var/mail/cltbld', 09:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:15 INFO - 'MOZ_NO_REMOTE': '1', 09:10:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:15 INFO - 'NO_EM_RESTART': '1', 09:10:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:15 INFO - 'PWD': '/builds/slave/test', 09:10:15 INFO - 'SHELL': '/bin/bash', 09:10:15 INFO - 'SHLVL': '1', 09:10:15 INFO - 'TERM': 'linux', 09:10:15 INFO - 'TMOUT': '86400', 09:10:15 INFO - 'USER': 'cltbld', 09:10:15 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:15 INFO - '_': '/tools/buildbot/bin/python'} 09:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:15 INFO - Downloading/unpacking psutil>=3.1.1 09:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:10:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:17 INFO - Installing collected packages: psutil 09:10:17 INFO - Running setup.py install for psutil 09:10:17 INFO - building 'psutil._psutil_linux' extension 09:10:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:10:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:10:17 INFO - building 'psutil._psutil_posix' extension 09:10:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:10:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:10:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:10:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:10:17 INFO - Successfully installed psutil 09:10:17 INFO - Cleaning up... 09:10:17 INFO - Return code: 0 09:10:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:10:17 INFO - Using env: {'DISPLAY': ':0', 09:10:17 INFO - 'HOME': '/home/cltbld', 09:10:17 INFO - 'LANG': 'en_US.UTF-8', 09:10:17 INFO - 'LANGUAGE': 'en_US:en', 09:10:17 INFO - 'LOGNAME': 'cltbld', 09:10:17 INFO - 'MAIL': '/var/mail/cltbld', 09:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:17 INFO - 'MOZ_NO_REMOTE': '1', 09:10:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:17 INFO - 'NO_EM_RESTART': '1', 09:10:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:17 INFO - 'PWD': '/builds/slave/test', 09:10:17 INFO - 'SHELL': '/bin/bash', 09:10:17 INFO - 'SHLVL': '1', 09:10:17 INFO - 'TERM': 'linux', 09:10:17 INFO - 'TMOUT': '86400', 09:10:17 INFO - 'USER': 'cltbld', 09:10:17 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:17 INFO - '_': '/tools/buildbot/bin/python'} 09:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:10:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:10:20 INFO - Installing collected packages: mozsystemmonitor 09:10:20 INFO - Running setup.py install for mozsystemmonitor 09:10:20 INFO - Successfully installed mozsystemmonitor 09:10:20 INFO - Cleaning up... 09:10:20 INFO - Return code: 0 09:10:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:10:20 INFO - Using env: {'DISPLAY': ':0', 09:10:20 INFO - 'HOME': '/home/cltbld', 09:10:20 INFO - 'LANG': 'en_US.UTF-8', 09:10:20 INFO - 'LANGUAGE': 'en_US:en', 09:10:20 INFO - 'LOGNAME': 'cltbld', 09:10:20 INFO - 'MAIL': '/var/mail/cltbld', 09:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:20 INFO - 'MOZ_NO_REMOTE': '1', 09:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:20 INFO - 'NO_EM_RESTART': '1', 09:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:20 INFO - 'PWD': '/builds/slave/test', 09:10:20 INFO - 'SHELL': '/bin/bash', 09:10:20 INFO - 'SHLVL': '1', 09:10:20 INFO - 'TERM': 'linux', 09:10:20 INFO - 'TMOUT': '86400', 09:10:20 INFO - 'USER': 'cltbld', 09:10:20 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:20 INFO - '_': '/tools/buildbot/bin/python'} 09:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:10:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:10:22 INFO - warning: no files found matching '*.txt' 09:10:22 INFO - no previously-included directories found matching 'build' 09:10:22 INFO - no previously-included directories found matching 'dist' 09:10:22 INFO - no previously-included directories found matching '.git*' 09:10:22 INFO - Installing collected packages: jsonschema, functools32 09:10:22 INFO - Running setup.py install for functools32 09:10:22 INFO - warning: no files found matching '*.txt' 09:10:22 INFO - no previously-included directories found matching 'build' 09:10:22 INFO - no previously-included directories found matching 'dist' 09:10:22 INFO - no previously-included directories found matching '.git*' 09:10:22 INFO - Successfully installed jsonschema functools32 09:10:22 INFO - Cleaning up... 09:10:22 INFO - Return code: 0 09:10:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:10:22 INFO - Using env: {'DISPLAY': ':0', 09:10:22 INFO - 'HOME': '/home/cltbld', 09:10:23 INFO - 'LANG': 'en_US.UTF-8', 09:10:23 INFO - 'LANGUAGE': 'en_US:en', 09:10:23 INFO - 'LOGNAME': 'cltbld', 09:10:23 INFO - 'MAIL': '/var/mail/cltbld', 09:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:23 INFO - 'MOZ_NO_REMOTE': '1', 09:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:23 INFO - 'NO_EM_RESTART': '1', 09:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:23 INFO - 'PWD': '/builds/slave/test', 09:10:23 INFO - 'SHELL': '/bin/bash', 09:10:23 INFO - 'SHLVL': '1', 09:10:23 INFO - 'TERM': 'linux', 09:10:23 INFO - 'TMOUT': '86400', 09:10:23 INFO - 'USER': 'cltbld', 09:10:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:23 INFO - '_': '/tools/buildbot/bin/python'} 09:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:10:23 INFO - Cleaning up... 09:10:23 INFO - Return code: 0 09:10:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:10:23 INFO - Using env: {'DISPLAY': ':0', 09:10:23 INFO - 'HOME': '/home/cltbld', 09:10:23 INFO - 'LANG': 'en_US.UTF-8', 09:10:23 INFO - 'LANGUAGE': 'en_US:en', 09:10:23 INFO - 'LOGNAME': 'cltbld', 09:10:23 INFO - 'MAIL': '/var/mail/cltbld', 09:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:23 INFO - 'MOZ_NO_REMOTE': '1', 09:10:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:23 INFO - 'NO_EM_RESTART': '1', 09:10:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:23 INFO - 'PWD': '/builds/slave/test', 09:10:23 INFO - 'SHELL': '/bin/bash', 09:10:23 INFO - 'SHLVL': '1', 09:10:23 INFO - 'TERM': 'linux', 09:10:23 INFO - 'TMOUT': '86400', 09:10:23 INFO - 'USER': 'cltbld', 09:10:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:23 INFO - '_': '/tools/buildbot/bin/python'} 09:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:23 INFO - Downloading/unpacking blobuploader==1.2.4 09:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:10:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:10:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:26 INFO - Downloading docopt-0.6.1.tar.gz 09:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:10:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:10:26 INFO - Running setup.py install for blobuploader 09:10:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:10:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:10:26 INFO - Running setup.py install for requests 09:10:26 INFO - Running setup.py install for docopt 09:10:26 INFO - Successfully installed blobuploader requests docopt 09:10:26 INFO - Cleaning up... 09:10:26 INFO - Return code: 0 09:10:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:26 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')]} 09:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:26 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')]} 09:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:26 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:26 INFO - Using env: {'DISPLAY': ':0', 09:10:26 INFO - 'HOME': '/home/cltbld', 09:10:26 INFO - 'LANG': 'en_US.UTF-8', 09:10:26 INFO - 'LANGUAGE': 'en_US:en', 09:10:26 INFO - 'LOGNAME': 'cltbld', 09:10:26 INFO - 'MAIL': '/var/mail/cltbld', 09:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:26 INFO - 'MOZ_NO_REMOTE': '1', 09:10:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:26 INFO - 'NO_EM_RESTART': '1', 09:10:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:26 INFO - 'PWD': '/builds/slave/test', 09:10:26 INFO - 'SHELL': '/bin/bash', 09:10:26 INFO - 'SHLVL': '1', 09:10:26 INFO - 'TERM': 'linux', 09:10:26 INFO - 'TMOUT': '86400', 09:10:26 INFO - 'USER': 'cltbld', 09:10:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:26 INFO - '_': '/tools/buildbot/bin/python'} 09:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:26 INFO - Running setup.py (path:/tmp/pip-xY5x5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:27 INFO - Running setup.py (path:/tmp/pip-FKssgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:27 INFO - Running setup.py (path:/tmp/pip-07Fnhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:27 INFO - Running setup.py (path:/tmp/pip-JIMYpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:27 INFO - Running setup.py (path:/tmp/pip-RP2tOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:27 INFO - Running setup.py (path:/tmp/pip-u7Zd83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:27 INFO - Running setup.py (path:/tmp/pip-A08O8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:27 INFO - Running setup.py (path:/tmp/pip-cAQSMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:27 INFO - Running setup.py (path:/tmp/pip-rGkPZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:27 INFO - Running setup.py (path:/tmp/pip-JTRHdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:27 INFO - Running setup.py (path:/tmp/pip-gbCPZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:27 INFO - Running setup.py (path:/tmp/pip-hdpsl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:27 INFO - Running setup.py (path:/tmp/pip-jv_IDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:27 INFO - Running setup.py (path:/tmp/pip-9BR_34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:28 INFO - Running setup.py (path:/tmp/pip-dr4Zaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:28 INFO - Running setup.py (path:/tmp/pip-Db8JUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:28 INFO - Running setup.py (path:/tmp/pip-sYlCiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:10:28 INFO - Running setup.py install for manifestparser 09:10:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:10:28 INFO - Running setup.py install for mozcrash 09:10:28 INFO - Running setup.py install for mozdebug 09:10:28 INFO - Running setup.py install for mozdevice 09:10:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:10:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:10:28 INFO - Running setup.py install for mozfile 09:10:28 INFO - Running setup.py install for mozhttpd 09:10:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:10:28 INFO - Running setup.py install for mozinfo 09:10:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:28 INFO - Running setup.py install for mozInstall 09:10:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Running setup.py install for mozleak 09:10:29 INFO - Running setup.py install for mozlog 09:10:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Running setup.py install for moznetwork 09:10:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Running setup.py install for mozprocess 09:10:29 INFO - Running setup.py install for mozprofile 09:10:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Running setup.py install for mozrunner 09:10:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:29 INFO - Running setup.py install for mozscreenshot 09:10:29 INFO - Running setup.py install for moztest 09:10:29 INFO - Running setup.py install for mozversion 09:10:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:30 INFO - Cleaning up... 09:10:30 INFO - Return code: 0 09:10:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:30 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')]} 09:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:30 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')]} 09:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:30 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:30 INFO - Using env: {'DISPLAY': ':0', 09:10:30 INFO - 'HOME': '/home/cltbld', 09:10:30 INFO - 'LANG': 'en_US.UTF-8', 09:10:30 INFO - 'LANGUAGE': 'en_US:en', 09:10:30 INFO - 'LOGNAME': 'cltbld', 09:10:30 INFO - 'MAIL': '/var/mail/cltbld', 09:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:30 INFO - 'MOZ_NO_REMOTE': '1', 09:10:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:30 INFO - 'NO_EM_RESTART': '1', 09:10:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:30 INFO - 'PWD': '/builds/slave/test', 09:10:30 INFO - 'SHELL': '/bin/bash', 09:10:30 INFO - 'SHLVL': '1', 09:10:30 INFO - 'TERM': 'linux', 09:10:30 INFO - 'TMOUT': '86400', 09:10:30 INFO - 'USER': 'cltbld', 09:10:30 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:30 INFO - '_': '/tools/buildbot/bin/python'} 09:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:30 INFO - Running setup.py (path:/tmp/pip-hWD86I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:30 INFO - Running setup.py (path:/tmp/pip-eqocll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:30 INFO - Running setup.py (path:/tmp/pip-pXkcU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:30 INFO - Running setup.py (path:/tmp/pip-UAo6Ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:30 INFO - Running setup.py (path:/tmp/pip-K6CZbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:30 INFO - Running setup.py (path:/tmp/pip-XYVW8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:30 INFO - Running setup.py (path:/tmp/pip-8pdn0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:30 INFO - Running setup.py (path:/tmp/pip-LLe7N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:30 INFO - Running setup.py (path:/tmp/pip-2MeD2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:30 INFO - Running setup.py (path:/tmp/pip-DJ5ZKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:30 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)) 09:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:30 INFO - Running setup.py (path:/tmp/pip-_vZJIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:31 INFO - Running setup.py (path:/tmp/pip-dFjbTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:31 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)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:31 INFO - Running setup.py (path:/tmp/pip-m_Zcwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:31 INFO - Running setup.py (path:/tmp/pip-ZTI9T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:31 INFO - Running setup.py (path:/tmp/pip-bvOmFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:31 INFO - Running setup.py (path:/tmp/pip-IdM9I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:10:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:31 INFO - Running setup.py (path:/tmp/pip-XlHzGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10: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 09:10:34 INFO - Downloading blessings-1.6.tar.gz 09:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:34 INFO - Installing collected packages: blessings 09:10:34 INFO - Running setup.py install for blessings 09:10:34 INFO - Successfully installed blessings 09:10:34 INFO - Cleaning up... 09:10:34 INFO - Return code: 0 09:10:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:34 INFO - Reading from file tmpfile_stdout 09:10:34 INFO - Current package versions: 09:10:34 INFO - argparse == 1.2.1 09:10:34 INFO - blessings == 1.6 09:10:34 INFO - blobuploader == 1.2.4 09:10:34 INFO - docopt == 0.6.1 09:10:34 INFO - functools32 == 3.2.3-2 09:10:34 INFO - jsonschema == 2.5.1 09:10:34 INFO - manifestparser == 1.1 09:10:34 INFO - mozInstall == 1.12 09:10:34 INFO - mozcrash == 1.0 09:10:34 INFO - mozdebug == 0.1 09:10:34 INFO - mozdevice == 0.49 09:10:34 INFO - mozfile == 1.2 09:10:34 INFO - mozhttpd == 0.7 09:10:34 INFO - mozinfo == 0.9 09:10:34 INFO - mozleak == 0.1 09:10:34 INFO - mozlog == 3.4 09:10:34 INFO - moznetwork == 0.27 09:10:34 INFO - mozprocess == 0.23 09:10:34 INFO - mozprofile == 0.28 09:10:34 INFO - mozrunner == 6.13 09:10:34 INFO - mozscreenshot == 0.1 09:10:34 INFO - mozsystemmonitor == 0.3 09:10:34 INFO - moztest == 0.7 09:10:34 INFO - mozversion == 1.4 09:10:34 INFO - psutil == 3.1.1 09:10:34 INFO - requests == 1.2.3 09:10:34 INFO - wsgiref == 0.1.2 09:10:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10: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')]} 09:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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')]} 09:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:34 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:34 INFO - Using env: {'DISPLAY': ':0', 09:10:34 INFO - 'HOME': '/home/cltbld', 09:10:34 INFO - 'LANG': 'en_US.UTF-8', 09:10:34 INFO - 'LANGUAGE': 'en_US:en', 09:10:34 INFO - 'LOGNAME': 'cltbld', 09:10:34 INFO - 'MAIL': '/var/mail/cltbld', 09:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:34 INFO - 'MOZ_NO_REMOTE': '1', 09:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:34 INFO - 'NO_EM_RESTART': '1', 09:10:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:34 INFO - 'PWD': '/builds/slave/test', 09:10:34 INFO - 'SHELL': '/bin/bash', 09:10:34 INFO - 'SHLVL': '1', 09:10:34 INFO - 'TERM': 'linux', 09:10:34 INFO - 'TMOUT': '86400', 09:10:34 INFO - 'USER': 'cltbld', 09:10:34 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:34 INFO - '_': '/tools/buildbot/bin/python'} 09:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:35 INFO - Cleaning up... 09:10:35 INFO - Return code: 0 09:10:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:35 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 0x7fad82354e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16a8120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, '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-1481043744.535647-1153519806', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:10:35 INFO - Using env: {'DISPLAY': ':0', 09:10:35 INFO - 'HOME': '/home/cltbld', 09:10:35 INFO - 'LANG': 'en_US.UTF-8', 09:10:35 INFO - 'LANGUAGE': 'en_US:en', 09:10:35 INFO - 'LOGNAME': 'cltbld', 09:10:35 INFO - 'MAIL': '/var/mail/cltbld', 09:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:35 INFO - 'MOZ_NO_REMOTE': '1', 09:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:35 INFO - 'NO_EM_RESTART': '1', 09:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:35 INFO - 'PWD': '/builds/slave/test', 09:10:35 INFO - 'SHELL': '/bin/bash', 09:10:35 INFO - 'SHLVL': '1', 09:10:35 INFO - 'TERM': 'linux', 09:10:35 INFO - 'TMOUT': '86400', 09:10:35 INFO - 'USER': 'cltbld', 09:10:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:35 INFO - '_': '/tools/buildbot/bin/python'} 09:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:10:35 INFO - Cleaning up... 09:10:35 INFO - Return code: 0 09:10:35 INFO - Running post-action listener: _resource_record_post_action 09:10:35 INFO - Running post-action listener: _start_resource_monitoring 09:10:35 INFO - Starting resource monitoring. 09:10:35 INFO - [mozharness: 2016-12-06 17:10:35.412543Z] Finished create-virtualenv step (success) 09:10:35 INFO - [mozharness: 2016-12-06 17:10:35.413064Z] Running install step. 09:10:35 INFO - Running pre-action listener: _resource_record_pre_action 09:10:35 INFO - Running main action method: install 09:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:35 INFO - Reading from file tmpfile_stdout 09:10:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:35 INFO - Reading from file tmpfile_stdout 09:10:35 INFO - Output received: 09:10:35 INFO - Usage: mozinstall [options] installer 09:10:35 INFO - Options: 09:10:35 INFO - -h, --help show this help message and exit 09:10:35 INFO - -d DEST, --destination=DEST 09:10:35 INFO - Directory to install application into. [default: 09:10:35 INFO - "/builds/slave/test"] 09:10:35 INFO - --app=APP Application being installed. [default: firefox] 09:10:35 INFO - mkdir: /builds/slave/test/build/application 09:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:10:52 INFO - Reading from file tmpfile_stdout 09:10:52 INFO - Output received: 09:10:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:10:52 INFO - Running post-action listener: _resource_record_post_action 09:10:52 INFO - [mozharness: 2016-12-06 17:10:52.669355Z] Finished install step (success) 09:10:52 INFO - [mozharness: 2016-12-06 17:10:52.669595Z] Running run-tests step. 09:10:52 INFO - Running pre-action listener: _resource_record_pre_action 09:10:52 INFO - Running main action method: run_tests 09:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:52 INFO - Python 2.7.3 09:10:52 INFO - Return code: 0 09:10:52 INFO - grabbing minidump binary from tooltool 09:10:52 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')]} 09:10:52 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 0x1738f20>, '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 0x16a5c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a5f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:10:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:10:52 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 09:10:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:52 INFO - Return code: 0 09:10:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:10:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:10:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:10:52 INFO - Using env: {'DISPLAY': ':0', 09:10:52 INFO - 'HOME': '/home/cltbld', 09:10:52 INFO - 'LANG': 'en_US.UTF-8', 09:10:52 INFO - 'LANGUAGE': 'en_US:en', 09:10:52 INFO - 'LOGNAME': 'cltbld', 09:10:52 INFO - 'MAIL': '/var/mail/cltbld', 09:10:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:52 INFO - 'MOZ_NO_REMOTE': '1', 09:10:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:10:52 INFO - 'NO_EM_RESTART': '1', 09:10:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:52 INFO - 'PWD': '/builds/slave/test', 09:10:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:52 INFO - 'SHELL': '/bin/bash', 09:10:52 INFO - 'SHLVL': '1', 09:10:52 INFO - 'TERM': 'linux', 09:10:52 INFO - 'TMOUT': '86400', 09:10:52 INFO - 'USER': 'cltbld', 09:10:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1481043744.535647-1153519806', 09:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:52 INFO - '_': '/tools/buildbot/bin/python'} 09:10:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:10:52 INFO - mozversion application_buildid: 20161206073109 09:10:52 INFO - mozversion application_changeset: deacac546efaedc8b7a5eef776bf9bba8398d401 09:10:52 INFO - mozversion application_display_name: Nightly 09:10:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:52 INFO - mozversion application_name: Firefox 09:10:52 INFO - mozversion application_remotingname: firefox 09:10:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:10:52 INFO - mozversion application_vendor: Mozilla 09:10:52 INFO - mozversion application_version: 53.0a1 09:10:52 INFO - mozversion platform_buildid: 20161206073109 09:10:52 INFO - mozversion platform_changeset: deacac546efaedc8b7a5eef776bf9bba8398d401 09:10:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:10:52 INFO - mozversion platform_version: 53.0a1 09:10:52 INFO - using testdate: 1481044252 09:10:52 INFO - actual date: 1481044252 09:10:52 INFO - starting webserver on 'localhost:57841' 09:10:52 INFO - SUITE-START | Running 2 tests 09:10:52 INFO - TEST-START | dromaeo_css 09:10:52 INFO - operating with platform_type : linux_ 09:10:52 INFO - Initialising browser for dromaeo_css test... 09:10:52 INFO - TEST-INFO | started process 18183 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKj7nS/profile http://localhost:57841/getInfo.html) 09:10:57 INFO - PROCESS | 18183 | __metrics Screen width/height:1600/1200 09:10:57 INFO - PROCESS | 18183 | colorDepth:24 09:10:57 INFO - PROCESS | 18183 | Browser inner width/height: 1024/697 09:10:57 INFO - PROCESS | 18183 | __metrics 09:10:57 INFO - PROCESS | 18183 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:57 INFO - PROCESS | 18183 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:58 INFO - PROCESS | 18183 | console.error: PushService: 09:10:58 INFO - PROCESS | 18183 | stateChangeProcessEnqueue: Error transitioning state 09:10:58 INFO - PROCESS | 18183 | UnknownError 09:10:59 INFO - TEST-INFO | 18183: exit 0 09:10:59 INFO - Browser initialized. 09:10:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:10:59 INFO - TEST-INFO | started process 18269 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKj7nS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:11 INFO - PROCESS | 18269 | RSS: Main: 171798528 09:11:11 INFO - PROCESS | 18269 | 09:12:02 INFO - PROCESS | 18269 | 1481044322602 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:14:13 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57841/tests/dromaeo/cssquery-ext.html) 09:14:14 INFO - PROCESS | 18269 | RSS: Main: 317239296 09:14:14 INFO - PROCESS | 18269 | 09:17:17 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-ext.html (next: http://localhost:57841/tests/dromaeo/cssquery-jquery.html) 09:17:17 INFO - PROCESS | 18269 | RSS: Main: 288149504 09:17:17 INFO - PROCESS | 18269 | 09:20:19 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57841/tests/dromaeo/cssquery-mootools.html) 09:20:20 INFO - PROCESS | 18269 | RSS: Main: 222146560 09:20:20 INFO - PROCESS | 18269 | 09:23:22 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57841/tests/dromaeo/cssquery-prototype.html) 09:23:23 INFO - PROCESS | 18269 | RSS: Main: 253837312 09:23:23 INFO - PROCESS | 18269 | 09:26:25 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57841/tests/dromaeo/cssquery-yui.html) 09:26:26 INFO - PROCESS | 18269 | RSS: Main: 223875072 09:26:26 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | Cycle 1(1): loaded http://localhost:57841/tests/dromaeo/cssquery-yui.html (next: http://localhost:57841/tests/dromaeo/cssquery-dojo.html) 09:29:29 INFO - PROCESS | 18269 | RSS: Main: 315039744 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | __start_tp_report 09:29:29 INFO - PROCESS | 18269 | _x_x_mozilla_page_load 09:29:29 INFO - PROCESS | 18269 | _x_x_mozilla_page_load_details 09:29:29 INFO - PROCESS | 18269 | |i|pagename|runs| 09:29:29 INFO - PROCESS | 18269 | |0;dojo.html;3219.3722224166286;3253.5770349854515;3259.445894197987;3289.276359200975;3312.8343582820867;5192;5207.479252074791;5245.26566280721;5325.547328477074;5337.279467271917;5260.421353651095;5282.498162674539;5365.54392876605;5366.382865970415;5395.244665746799;4765.952340476586;4775.331453596499;4794.736289504076;4818.975905120469;4827.8310259140935;4880.048390563841;4890.413150421954;4896.632752543574;4910.386201724785;4938.185199442088;4779.115863565252;4875.268709693539;4897.681650692693;4915;4984.102861484931;4793.976030119862;4853.126437241295;4856.878578035542;4861.6353773467135;4864.075825593161;4819.831305904277;4843.104025755211;4851.272309153655;4851.975740121312;4854.902901941977;5253.553447956928;5291.100512912814;5292.655977361459;5299.8410047698635;5302.337207849019;5642.576806739511;5689.032864413019;5696.202531645573;5724.055530837449;5772.913406298909;5740.052891272935;5758;5767.942320576824;5769.24999750034;5770.711464426804;5221.503957124067;5322.574194064504;5379.731013449312;5382.273393091921;5386.5152136307925;5684.857878553069;5765.1640512125505;5776.653400795965;5780.826575202751;5847;5578.944210557843;5584.91622625661;5588.720563971785;5590.049691552445;5590.413006634326;5640.802571910045;5656.2929633795775;5658.0381335173115;5658.660480371191;5659.745311460994;5552.056150454432;5599.160125981054;5604.299462567179;5631.915521267102;5647.548196144298;5680.11958146488;5686.914696279559;5695.601307908407;5702;5723.198752174699;4390.560943905584;4394.450693663292;4398.450193725785;4402.119576084796;4442.111577684476;3909.198614284065;3926.2147570485445;3931.8034098295543;3968.0675041365257;3997.780122093313;5250.317458730417;5280.471952804689;5321.122014867581;5369.570434365241;5378;5607.186957891083;5659.43405659439;5661.971690141523;5663.122216056436;5667;5291.391489978625;5327.0411325961695;5338.252644629755;5339.359276886721;5386.946130538723;4831.0820944021225;4837.32277481153;4847.709137451764;4862.149123903303;4875.926861097087;5794.159846822272;5797.884042319214;5801.158831969426;5807.82576522705;5850.1224816277045;5625.128105143709;5631.183478395527;5636.774529018793;5637.887242255214;5640.689762063126;5662.150677398423;5713.685747283773;5812.651240925558;5822.534197264124;5831.300243970751;5552.888942221214;5559.527440167631;5600.887982240414;5653.971730141323;5655.321361436654;5629.577781666473;5630.155476678367;5632.85917852057;5638.605297629127;5684.062129748545;5355.812546560852;5399.973000135132;5542.7782888685615;5556.888862222652;5576.358718747396;5566.025945459609;5571.749271282721;5580.38615752275;5618.859528511656;5622.297212848394;5466.070767969375;5486.451354864482;5495.862603434946;5507.476789704971;5507.807226747044;5467.234587157881;5502.477264659851;5596.188552659923;5607.831765047055;5654.15187721845;5405.162199859028;5449.618526703092;5458.072127738374;5459.972700136474;5462.098753705594 09:29:29 INFO - PROCESS | 18269 | |1;ext.html;548.8710153114017;577.9017567013607;589.3399392680199;624.5409623926413;641.2081079866367;30709;39763.2047359053;40973.180536389256;41100.99999999996;43472.347914781305;41709;42158.789206054;42202.7889860551;42241.788791056;42242.7887860561;7857.6856925722905;8409.20112589303;8562.871556926651;8563.828723425528;8567.485950842938;7948.682052717884;8053.1141574426765;8071.273585377315;9924.454155021507;10169.949150254239;10228.43743594102;10292.639757608486;10300.587976480932;10307.278490505667;10309.63916262931;10108.898911010943;10215.387076775418;10215.489225538731;10251.487425628764;10264.486775661187;9706;9753.414795112245;9762.170215531689;9762.90237097627;9766.218702503782;38942.415863762064;39099.41350879739;39152;39295;39305.60694393048;3303.4714445688815;3351.8826841060445;3473.6179020307745;3540.327337805809;3729.925401491982;29691.70308296911;30175.547366789517;30214.395712085636;30242.395152097055;30247.243818904484;4024.476818013669;4033.3748269018347;4123.8556650517085;4146.3780432935;4271.337942618899;315703;316453;316977;317369;317401;55157.448425516035;55620.443795562336;56011;56059.719701401234;56064.91642959595;58480;59025.621797275344;59100.7044964781;59112;59131.11303330454;15343.309551070228;15864.682706345808;15869.52391428259;15885;15892.364305427885;10443.806800571965;12326.938365308117;12481.56314528987;12488;12495;15734.527964161094;16489.67020659604;16720.16399180036;16822.57943551421;16944.23750931211;6664.666766661648;6677.899831502531;6784.287649796799;6811.557248778813;6906.204386859453;882.6866462405882;1001.384148748529;1009.5406590001531;1018.5416562546884;1051.2746205118444;28469.288267793057;28487.14538563805;28736.281592960342;29161;29322.853385732935;1041.1774697988656;1070.4326706845384;1072.5280876414354;1134.9546018159347;1164.1443538998828;14530.346134423977;15186.924065379602;15291.235438228045;15305.693886122215;15384.30770615325;439553;442487;443245;443562;470722;43806.780966094964;52117.218241726405;53280.73359633177;53375.199372009454;58263.417365826645;1293.6313150751992;1311.2132720367895;1350.0549615269351;1350.3383342162467;1361.8910487161004;27579;28553.714462855936;29377.441625785705;29759.25601859971;29930.25174370658;48059.75970120127;48551.271730923356;48579.757101215684;48621.756891215315;48667.269990950874;392586;396589;397008;397560;399986;27014.729852702007;27175.456490870467;27383.315417114733;27453.450930980867;27658.3085422858;43612.34581481217;43988.91746295468;44785;44978;45168;36173.81913090523;38103.618963810004;38466.2306753869;38848;39677;37280;37387.89629970297;37703.81148094242;38319;38820.41769373518;38040.61959380481;38554.80722596369;38647.03382415462;38672.22655546929;38847.08646851554;32068.5189722159;32911.8354408236;33368.33263334768;34269;34493.482597761555;26289;26587;26684.599731003662;26854.59718104269;26923.90310671977 09:29:29 INFO - PROCESS | 18269 | |2;jquery.html;355.5804151101699;366.9324447617914;380.38947489279695;385.452657226738;401.6746435387337;14267.143971361713;14650.34073466694;18447.723284150732;18452.261909523637;18500.16749246284;17673.469795906145;18425.90787046066;18535.72196417055;18912.71630925537;18986.62026759464;3863.555691095532;5067.974660126694;5226.24219488174;5226.529612334889;5230.137027390476;4003.859864904729;6575.243846957614;6812.62530560819;6871.312868713114;6895.138107736533;6722.1261236039245;6881.552699074544;6882.139732533434;7151.749688760894;7213.531120477179;7046.541974771623;7233.638318084101;7270.491065625409;7277.563346199192;7286.198518162998;6991.161060672701;7022.227554968949;7034.824129396755;7037.718491260343;7038.3665470107935;23347.649785253234;24026.519469610685;24295.757042429526;25068;25256.242312730647;2412.1678021082876;2447.963280550793;2515.333436639292;2586.482703459316;2614.6993095794037;14332;15557.844421555752;15601.843981560152;15768.369265229492;16186.514404567883;2038.2051000109968;2089.185217765073;2104.116271166114;2104.2529901884764;2138.0485683870684;88386;88519.91840652797;88650.5567472165;88820;88881.55559222163;16714.4149954751;16761.41335053267;17144.314227430765;17179.196184400458;17336.65326693459;29904;29985;30004.69995300063;30075.992400759747;30205.69794302073;9095.727128186165;9215.308851836138;9358.953205233931;9552.092551207625;9568.808623827623;7583.2416758323725;7628.160902300742;7694.692212311445;7879.803004924923;7926.920730792734;8464.534450605153;8479.872801907977;8532.317414606816;8546.78633034179;8582.326446342904;3356.5300857879915;3532.5693094296853;3594.299111673251;3628.528291322164;3649.9817500912327;834.9874751878726;928.6192661009089;985.1773768902902;1004.0160642570322;1017.2523195451338;17271.39550115765;17872.195751191226;18384.17271222798;18461.5384615383;18844;964.9228061755043;975.5463709375175;975.9512024398751;977.39401571025;986.7335819328885;9718.951405242928;10344.5344959477;10422.426766527913;10426;10430.947845260725;440770;442105;445296;452901;455338;83871;84925;86613;86900;86909.56545217233;950.8193443245899;957.8802649668792;967.9225661947028;968.9127978481971;971.7376308396834;15635.140067295953;15962.920185398998;16069.839301607302;16554.66890662156;16825.74761378556;37444.81277593595;38677.80661096676;38726;39298;40925.386119207644;51352.74323628358;51960;52627;53191;53343.73328133334;7161.9641901790155;8316.126806685212;8373.95813020931;8415.579221039046;8582.828343432971;12103.576374826838;12238.388080596113;12455.501779929064;12488.12583119173;13144.0799144059;10936.398498082683;10979.694264204607;11010.229283950366;11245;11265;6972.9302706972285;11024.283421577571;11104.500297486471;11271.154663400113;11293.378864162221;11274.210805243882;11350.602728904449;11391.145664075393;11425.771484570429;11578.131640126856;7687.231276872268;7747.690092396241;7874.527528348317;7923.445358825057;7987.642100842987;9550.953006247353;11041.33751974884;11204.551817927191;11562.132840036862;11630.825537617111 09:29:29 INFO - PROCESS | 18269 | |3;mootools.html;1336.4453751693047;1401.257333613185;1410.2243765928736;1496.4837131189736;1699.2693141948964;7124.643767811608;7570.356519695825;7765.572893490863;7769.261920117594;7914.366850651948;7807.258310460511;7874.370050395969;7876.133625301212;7890.487118337319;7894.763157105282;2020.0707674469688;2084.718562993999;2228.888555572219;2274.37454699958;2276.35123989663;2256.3907744908865;2318.5710643530915;2326.639370897514;2330.3242059802637;2344.882755862209;2254.966175507369;2274.920377786778;2286.2683941138757;2287.7597852225526;2306.0660432524855;2244.5735310291075;2258.5482903419384;2264.547090581874;2266.4220623741007;2266.8299877509253;2222.5777102350503;2267.693861328716;2269.4439862233694;2270.0919632147106;2271.670607761882;8075.959620201861;8124.22819832115;8161.877571836427;8193.221643943816;8196.180381961816;4978.551930326288;5101.872453188663;5103.923441148386;5112.335396398482;5118.38579370478;3068.447679417704;3262.429074911881;3264.6735326467165;3273.4271502486968;3279.376918385523;963.3160456076195;1045.4929359260752;1046.884842667306;1934.4390126863193;1972.102693274571;10883.510242039127;11017.063549598293;11081.72295692598;11092.722681932935;11104.666859994133;4156.418101465737;4438.889027774331;4477.350784136282;4488.147252022105;4497.77511124449;8788.472691638523;8791.868121978176;8800.47197168172;8802.559872006374;8816.735497935073;5060.620453465931;5084.720340381315;5089.6691715038405;5095.286659867622;5101.362329708786;4639.6313087638555;4674.158651442773;4729.95270047302;4747.7626118693925;4770.928436073462;3512.8946131616094;3553.8400771965325;3560.169665116239;3605.369060414417;3638.799866007342;1111.8554587903682;1125.7579620381657;1152.1646806065535;1155.2259985809528;1155.6186458468685;786.6420778545748;856.5225185745892;881.4226681523612;915.190056799737;949.3164921256684;5512.91730624041;5597.916031259534;5676;5771.163181338682;5926.259217597801;898.6116450084635;900.2752784008804;930.497531333086;954.9379290346105;973.3867663372031;3794.4687743715904;3944.980275098606;3988.4216788565495;3993.6405723485027;4020.115574573589;9721.270904682176;10092.192624590016;10099.394036357842;10116.797664046679;10118.190544756402;10020;10027.899721002697;10032.598696052222;10042.297039207186;10076.748081298027;7548.962255188689;7617.6572054257695;7687.730929417444;7700.807479813049;7747.806304842311;5225.085610018307;5576.386597474194;5603.7758489659955;5679.119736440858;5856.648601083778;8958.68644597436;9017.729468115967;9077.591508382007;9123.954380228057;9126.08739126082;9323.114304141096;9380.437173769596;9383.624655013722;9422.246220302497;9498.620055197714;6157.291911430243;6320.905186422299;6331.145295385064;6449.903251451138;6452.9677351612945;7596.392288616787;7726.304632583095;7731.806704832424;7775.066991960774;7945.6027198640995;6945.652717364213;7068.611226382598;7111;7112.288771122846;7369.742059028123;6590.90113648286;6635.104260924713;6700.93299067003;6906.412954898889;7124.786256412316;6476.384743449365;6631.403173714389;6638.0374845648075;6639.070530125689;6644.966775166094;4725.36207611968;4849.587785038311;4884.877878053077;5017.071841709296;5169.53474187325;4893.7063776173545;4953.108440480604;5087.542121209109;5146.382434107931;5192.714400707997 09:29:29 INFO - PROCESS | 18269 | |4;prototype.html;302.313747792511;313.39827531140213;316.82257935556106;318.4745170468728;320.54162547556984;5134.589232861372;5188.974055129728;5254.3432070991075;5317.361916570012;5345.946540534598;5026.82406115786;5119.6672216305915;5142.511461411169;5142.6914385136915;5156.2265660151;4029.6776257899446;4039.2325458163;4041.3533834586474;4042.4946881639794;4061.4110953911663;3892.0853599404118;3947.6841852651864;4066.6949978751563;4079.8164082616204;4079.9796001019954;3828.8659896903614;4021.758694478326;4045.797710114497;4051.372037334218;4083.3874918762276;4048.4939382577177;4052.797360131982;4053.97973010136;4068.511778586559;4076.30702780528;3588.5155504006884;3591.2278860045217;3600.71194304455;3602.0814692253307;3607.6572725591027;4892.6575139740235;5027.260143221953;5040.521150490697;5046.823361182378;5140.3831540215415;4537.954620453786;4542.545745425431;4552.658550608718;4561.589456948891;4571.1771881061395;5393.541548968343;5404.756785944603;5429.375621803503;5496.80761173361;5498.202760599731;3685.4471829225563;3820.777351247602;3825.8469661213517;3857.324968130566;3861.9806900965596;5616.326040875121;5637.548996080303;5643.351014633287;5646.362554859086;5656.2929633795775;3951.5060617422823;3960.7821569813937;3967.821448034845;3975.185087057146;3985.601439855991;3934.272159650474;3999.34010888206;4030.375291829771;4053.3717273822604;4053.6959728020515;4606.608438282717;4612.930806037912;4615.423072115986;4617.930731039037;4629.0973260214605;5456.945430545644;5473.909264384161;5474.370447398521;5491.7254137292975;5502.202180683858;1284.7302066566126;1330.1287656584952;1354.9051566390258;1355.5323413422354;1357.8234829472106;901.3555307954713;929.6560272699145;933.8926023507248;939.2392162348519;940.7977284883783;5069.9746501267255;5095.210242412432;5109.744512774347;5113.74431278442;5134.589232861362;5377.78488860449;5393.78424863009;5399.433059528771;5400.568849254951;5407.80939245839;4980;5077.629040159136;5128.640995130379;5141.948580514222;5146.459621739738;2868.655761308656;2879.481693295227;2885.5960165576835;2894.6381702287213;2894.7394734473996;5587.580931430159;5600.35595906476;5600.507774040513;5616.213730077792;5651.180578816049;5348.224507446398;5452.047265732313;5479.178123281539;5519.834404967857;5520.861978450491;5593.496585307342;5602.831915042637;5602.915956260659;5615.185798059259;5628.831135065954;5254.973725131503;5418.403975562606;5429.393890932453;5472.616916815944;5486.725663716718;5344.652597581145;5358.517733404013;5368.624196306221;5371.865703357447;5374.704391258519;5539;5548.334199896039;5550.084236100998;5611.438856114356;5645.802396916089;3727.9189035179493;3843.8660595125066;3857.9035524112123;3860.613938606117;3875.7286989910435;5646.108553634578;5647.88704225905;5655.830325090254;5672.290963629546;5675.375708671961;5508.504234618904;5525.806596769094;5534.39121696605;5559.19391688211;5575.856949325297;5321.787128514816;5454.3454785426;5518.586106042143;5556.555475561864;5576.442355764553;5447.591430642797;5463.470228336072;5463.644863083887;5465.098258787256;5465.234867118527;5529.4470552944385;5552.972235138798;5554.555815488083;5562.694051827189;5586.581006424616;5332.9733351333;5396.757145928521;5402.621816472809;5420.241166236651;5453.14593038353 09:29:29 INFO - PROCESS | 18269 | |5;yui.html;708.8369674974753;737.5464089584906;759.9012128423304;785.9017622797151;802.0856223904748;15403.306851191695;16204.94667846589;16217.108059056747;16231.431899883506;16331.428400006002;14537.127772333668;14656.780148297785;14834.55496335109;14837.109773413602;14837.925810370933;665.9101021362127;684.8527566573185;767.6238643064913;781.5427974634831;789.4039999800171;792.1207459719707;792.3581898662102;794.9046114466272;795.0499153511556;797.7287722174459;853.8847255620504;855.2772906893696;855.3456605696647;855.4054931822386;855.632078206371;843.2621456225802;852.9786755331166;853.14685314685;853.3514528958036;853.4111463090447;510.60428168169966;519.8050730975884;592.1946153231602;599.8590331272147;612.5589575505674;14754.778678319833;14842.332095055748;14842.628934276621;14857.331420086122;14857.47998820047;13810.309484525835;13812.723745525032;13937.442502299897;13979.440822367093;14061;12043.337616431092;12153.210041347284;12187.32969686667;12449.502019919193;13069.215847049207;4182.163567286555;4220.767857767822;4315.590018948226;4421.226285400042;4425.623821975136;76670.61664691697;76753.84869227021;77404.22595774083;77555.61222193853;78560.60719696365;10348.120409765253;10420.426876521864;10724.89275107239;10819.621313254156;10828.012769353614;20559.177632894516;20944.952752362322;21075.68386474204;21188.682169767464;21390;7712.267334603203;7771.922280777233;7829.64766585505;7925.960370198228;8413.789655258668;4302.978485107555;4400.053988392511;4403.163398954189;4447.08834688888;4499.977500112478;3067.9846600766855;3126.374725054998;3126.8280244586767;3129.85915633797;3136.247300647828;552.7512619321241;563.1918197386763;583.4486410342271;584.0625795598089;590.4331841432187;602.4367216652356;613.3406587918006;638.9297177310492;640.8013515810114;669.8794217040881;8846.46921184731;9386.85919711205;9469.715908522756;9512.47681377517;9624.181944534654;503.54177698294376;523.5785192919736;539.323149447441;610.8198081565948;638.3871483376012;4027.979860100739;4185.895352616209;4216.254325731929;4266.829326826892;4366.235908715962;83072.78517400194;84314;86268.56865715631;87324.56337718398;88044.5597772007;74705;76669.6166519175;76859.61570192114;77775.61112194402;78273;68353;68535.65732171308;68900.65549672219;69232.65383673049;69652;8606.65573377083;8940.418872773249;9226.815463690822;9308.177351660011;9360.578773955325;40498.59501404948;41183.7940810294;42121.157576847676;42703.505377311645;44012.019459125055;58408.415915842;58528;60255;60700;62212;11237.494312755782;11422.543098276308;11437.828432573351;11575.652730418102;12015.519379224734;12303.138780285293;12396.628101156979;12439.813402799145;12465.626031219077;12645.620631381073;12159.331236782062;12705.809412859;12833.165844220095;12985.155964862253;13859.722805544034;13112.788214248369;13461.117196057112;13820.021325662867;14055.22696251716;14427.062240954305;12819.615411537296;12969.675758106123;13503.59489215325;13649.454021839016;13746.312684365941;10188.286819922534;10267.383956962607;10337.276390652582;10417.374957502574;10675.519601618102;10944.45277736094;11020.393878336448;11032.172587055842;11069.778604428027;11235.15736319763 09:29:29 INFO - PROCESS | 18269 | __end_tp_report 09:29:29 INFO - PROCESS | 18269 | __start_cc_report 09:29:29 INFO - PROCESS | 18269 | _x_x_mozilla_cycle_collect,1418 09:29:29 INFO - PROCESS | 18269 | __end_cc_report 09:29:29 INFO - PROCESS | 18269 | __startTimestamp1481045369792__endTimestamp 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | ------- Summary: start ------- 09:29:29 INFO - PROCESS | 18269 | Number of tests: 6 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#0] dojo.html Cycles:165 Average:5275.98 Median:5466.07 stddev:558.51 (10.2%) stddev-sans-first:536.41 09:29:29 INFO - PROCESS | 18269 | Values: 3219.4 3253.6 3259.4 3289.3 3312.8 5192.0 5207.5 5245.3 5325.5 5337.3 5260.4 5282.5 5365.5 5366.4 5395.2 4766.0 4775.3 4794.7 4819.0 4827.8 4880.0 4890.4 4896.6 4910.4 4938.2 4779.1 4875.3 4897.7 4915.0 4984.1 4794.0 4853.1 4856.9 4861.6 4864.1 4819.8 4843.1 4851.3 4852.0 4854.9 5253.6 5291.1 5292.7 5299.8 5302.3 5642.6 5689.0 5696.2 5724.1 5772.9 5740.1 5758.0 5767.9 5769.2 5770.7 5221.5 5322.6 5379.7 5382.3 5386.5 5684.9 5765.2 5776.7 5780.8 5847.0 5578.9 5584.9 5588.7 5590.0 5590.4 5640.8 5656.3 5658.0 5658.7 5659.7 5552.1 5599.2 5604.3 5631.9 5647.5 5680.1 5686.9 5695.6 5702.0 5723.2 4390.6 4394.5 4398.5 4402.1 4442.1 3909.2 3926.2 3931.8 3968.1 3997.8 5250.3 5280.5 5321.1 5369.6 5378.0 5607.2 5659.4 5662.0 5663.1 5667.0 5291.4 5327.0 5338.3 5339.4 5386.9 4831.1 4837.3 4847.7 4862.1 4875.9 5794.2 5797.9 5801.2 5807.8 5850.1 5625.1 5631.2 5636.8 5637.9 5640.7 5662.2 5713.7 5812.7 5822.5 5831.3 5552.9 5559.5 5600.9 5654.0 5655.3 5629.6 5630.2 5632.9 5638.6 5684.1 5355.8 5400.0 5542.8 5556.9 5576.4 5566.0 5571.7 5580.4 5618.9 5622.3 5466.1 5486.5 5495.9 5507.5 5507.8 5467.2 5502.5 5596.2 5607.8 5654.2 5405.2 5449.6 5458.1 5460.0 5462.1 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#1] ext.html Cycles:180 Average:54370.14 Median:38072.12 stddev:103244.83 (271.2%) stddev-sans-first:103455.38 09:29:29 INFO - PROCESS | 18269 | Values: 548.9 577.9 589.3 624.5 641.2 30709.0 39763.2 40973.2 41101.0 43472.3 41709.0 42158.8 42202.8 42241.8 42242.8 7857.7 8409.2 8562.9 8563.8 8567.5 7948.7 8053.1 8071.3 9924.5 10169.9 10228.4 10292.6 10300.6 10307.3 10309.6 10108.9 10215.4 10215.5 10251.5 10264.5 9706.0 9753.4 9762.2 9762.9 9766.2 38942.4 39099.4 39152.0 39295.0 39305.6 3303.5 3351.9 3473.6 3540.3 3729.9 29691.7 30175.5 30214.4 30242.4 30247.2 4024.5 4033.4 4123.9 4146.4 4271.3 315703.0 316453.0 316977.0 317369.0 317401.0 55157.4 55620.4 56011.0 56059.7 56064.9 58480.0 59025.6 59100.7 59112.0 59131.1 15343.3 15864.7 15869.5 15885.0 15892.4 10443.8 12326.9 12481.6 12488.0 12495.0 15734.5 16489.7 16720.2 16822.6 16944.2 6664.7 6677.9 6784.3 6811.6 6906.2 882.7 1001.4 1009.5 1018.5 1051.3 28469.3 28487.1 28736.3 29161.0 29322.9 1041.2 1070.4 1072.5 1135.0 1164.1 14530.3 15186.9 15291.2 15305.7 15384.3 439553.0 442487.0 443245.0 443562.0 470722.0 43806.8 52117.2 53280.7 53375.2 58263.4 1293.6 1311.2 1350.1 1350.3 1361.9 27579.0 28553.7 29377.4 29759.3 29930.3 48059.8 48551.3 48579.8 48621.8 48667.3 392586.0 396589.0 397008.0 397560.0 399986.0 27014.7 27175.5 27383.3 27453.5 27658.3 43612.3 43988.9 44785.0 44978.0 45168.0 36173.8 38103.6 38466.2 38848.0 39677.0 37280.0 37387.9 37703.8 38319.0 38820.4 38040.6 38554.8 38647.0 38672.2 38847.1 32068.5 32911.8 33368.3 34269.0 34493.5 26289.0 26587.0 26684.6 26854.6 26923.9 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#2] jquery.html Cycles:180 Average:28388.98 Median:38701.90 stddev:73837.14 (190.8%) stddev-sans-first:74014.27 09:29:29 INFO - PROCESS | 18269 | Values: 355.6 366.9 380.4 385.5 401.7 14267.1 14650.3 18447.7 18452.3 18500.2 17673.5 18425.9 18535.7 18912.7 18986.6 3863.6 5068.0 5226.2 5226.5 5230.1 4003.9 6575.2 6812.6 6871.3 6895.1 6722.1 6881.6 6882.1 7151.7 7213.5 7046.5 7233.6 7270.5 7277.6 7286.2 6991.2 7022.2 7034.8 7037.7 7038.4 23347.6 24026.5 24295.8 25068.0 25256.2 2412.2 2448.0 2515.3 2586.5 2614.7 14332.0 15557.8 15601.8 15768.4 16186.5 2038.2 2089.2 2104.1 2104.3 2138.0 88386.0 88519.9 88650.6 88820.0 88881.6 16714.4 16761.4 17144.3 17179.2 17336.7 29904.0 29985.0 30004.7 30076.0 30205.7 9095.7 9215.3 9359.0 9552.1 9568.8 7583.2 7628.2 7694.7 7879.8 7926.9 8464.5 8479.9 8532.3 8546.8 8582.3 3356.5 3532.6 3594.3 3628.5 3650.0 835.0 928.6 985.2 1004.0 1017.3 17271.4 17872.2 18384.2 18461.5 18844.0 964.9 975.5 976.0 977.4 986.7 9719.0 10344.5 10422.4 10426.0 10430.9 440770.0 442105.0 445296.0 452901.0 455338.0 83871.0 84925.0 86613.0 86900.0 86909.6 950.8 957.9 967.9 968.9 971.7 15635.1 15962.9 16069.8 16554.7 16825.7 37444.8 38677.8 38726.0 39298.0 40925.4 51352.7 51960.0 52627.0 53191.0 53343.7 7162.0 8316.1 8374.0 8415.6 8582.8 12103.6 12238.4 12455.5 12488.1 13144.1 10936.4 10979.7 11010.2 11245.0 11265.0 6972.9 11024.3 11104.5 11271.2 11293.4 11274.2 11350.6 11391.1 11425.8 11578.1 7687.2 7747.7 7874.5 7923.4 7987.6 9551.0 11041.3 11204.6 11562.1 11630.8 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#3] mootools.html Cycles:180 Average:5353.14 Median:5101.62 stddev:2916.05 (57.2%) stddev-sans-first:2908.60 09:29:29 INFO - PROCESS | 18269 | Values: 1336.4 1401.3 1410.2 1496.5 1699.3 7124.6 7570.4 7765.6 7769.3 7914.4 7807.3 7874.4 7876.1 7890.5 7894.8 2020.1 2084.7 2228.9 2274.4 2276.4 2256.4 2318.6 2326.6 2330.3 2344.9 2255.0 2274.9 2286.3 2287.8 2306.1 2244.6 2258.5 2264.5 2266.4 2266.8 2222.6 2267.7 2269.4 2270.1 2271.7 8076.0 8124.2 8161.9 8193.2 8196.2 4978.6 5101.9 5103.9 5112.3 5118.4 3068.4 3262.4 3264.7 3273.4 3279.4 963.3 1045.5 1046.9 1934.4 1972.1 10883.5 11017.1 11081.7 11092.7 11104.7 4156.4 4438.9 4477.4 4488.1 4497.8 8788.5 8791.9 8800.5 8802.6 8816.7 5060.6 5084.7 5089.7 5095.3 5101.4 4639.6 4674.2 4730.0 4747.8 4770.9 3512.9 3553.8 3560.2 3605.4 3638.8 1111.9 1125.8 1152.2 1155.2 1155.6 786.6 856.5 881.4 915.2 949.3 5512.9 5597.9 5676.0 5771.2 5926.3 898.6 900.3 930.5 954.9 973.4 3794.5 3945.0 3988.4 3993.6 4020.1 9721.3 10092.2 10099.4 10116.8 10118.2 10020.0 10027.9 10032.6 10042.3 10076.7 7549.0 7617.7 7687.7 7700.8 7747.8 5225.1 5576.4 5603.8 5679.1 5856.6 8958.7 9017.7 9077.6 9124.0 9126.1 9323.1 9380.4 9383.6 9422.2 9498.6 6157.3 6320.9 6331.1 6449.9 6453.0 7596.4 7726.3 7731.8 7775.1 7945.6 6945.7 7068.6 7111.0 7112.3 7369.7 6590.9 6635.1 6700.9 6906.4 7124.8 6476.4 6631.4 6638.0 6639.1 6645.0 4725.4 4849.6 4884.9 5017.1 5169.5 4893.7 4953.1 5087.5 5146.4 5192.7 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#4] prototype.html Cycles:180 Average:4546.66 Median:5172.60 stddev:1340.36 (25.9%) stddev-sans-first:1305.72 09:29:29 INFO - PROCESS | 18269 | Values: 302.3 313.4 316.8 318.5 320.5 5134.6 5189.0 5254.3 5317.4 5345.9 5026.8 5119.7 5142.5 5142.7 5156.2 4029.7 4039.2 4041.4 4042.5 4061.4 3892.1 3947.7 4066.7 4079.8 4080.0 3828.9 4021.8 4045.8 4051.4 4083.4 4048.5 4052.8 4054.0 4068.5 4076.3 3588.5 3591.2 3600.7 3602.1 3607.7 4892.7 5027.3 5040.5 5046.8 5140.4 4538.0 4542.5 4552.7 4561.6 4571.2 5393.5 5404.8 5429.4 5496.8 5498.2 3685.4 3820.8 3825.8 3857.3 3862.0 5616.3 5637.5 5643.4 5646.4 5656.3 3951.5 3960.8 3967.8 3975.2 3985.6 3934.3 3999.3 4030.4 4053.4 4053.7 4606.6 4612.9 4615.4 4617.9 4629.1 5456.9 5473.9 5474.4 5491.7 5502.2 1284.7 1330.1 1354.9 1355.5 1357.8 901.4 929.7 933.9 939.2 940.8 5070.0 5095.2 5109.7 5113.7 5134.6 5377.8 5393.8 5399.4 5400.6 5407.8 4980.0 5077.6 5128.6 5141.9 5146.5 2868.7 2879.5 2885.6 2894.6 2894.7 5587.6 5600.4 5600.5 5616.2 5651.2 5348.2 5452.0 5479.2 5519.8 5520.9 5593.5 5602.8 5602.9 5615.2 5628.8 5255.0 5418.4 5429.4 5472.6 5486.7 5344.7 5358.5 5368.6 5371.9 5374.7 5539.0 5548.3 5550.1 5611.4 5645.8 3727.9 3843.9 3857.9 3860.6 3875.7 5646.1 5647.9 5655.8 5672.3 5675.4 5508.5 5525.8 5534.4 5559.2 5575.9 5321.8 5454.3 5518.6 5556.6 5576.4 5447.6 5463.5 5463.6 5465.1 5465.2 5529.4 5553.0 5554.6 5562.7 5586.6 5333.0 5396.8 5402.6 5420.2 5453.1 09:29:29 INFO - PROCESS | 18269 | 09:29:29 INFO - PROCESS | 18269 | [#5] yui.html Cycles:180 Average:18026.20 Median:4412.19 stddev:24018.23 (544.4%) stddev-sans-first:24050.40 09:29:29 INFO - PROCESS | 18269 | Values: 708.8 737.5 759.9 785.9 802.1 15403.3 16204.9 16217.1 16231.4 16331.4 14537.1 14656.8 14834.6 14837.1 14837.9 665.9 684.9 767.6 781.5 789.4 792.1 792.4 794.9 795.0 797.7 853.9 855.3 855.3 855.4 855.6 843.3 853.0 853.1 853.4 853.4 510.6 519.8 592.2 599.9 612.6 14754.8 14842.3 14842.6 14857.3 14857.5 13810.3 13812.7 13937.4 13979.4 14061.0 12043.3 12153.2 12187.3 12449.5 13069.2 4182.2 4220.8 4315.6 4421.2 4425.6 76670.6 76753.8 77404.2 77555.6 78560.6 10348.1 10420.4 10724.9 10819.6 10828.0 20559.2 20945.0 21075.7 21188.7 21390.0 7712.3 7771.9 7829.6 7926.0 8413.8 4303.0 4400.1 4403.2 4447.1 4500.0 3068.0 3126.4 3126.8 3129.9 3136.2 552.8 563.2 583.4 584.1 590.4 602.4 613.3 638.9 640.8 669.9 8846.5 9386.9 9469.7 9512.5 9624.2 503.5 523.6 539.3 610.8 638.4 4028.0 4185.9 4216.3 4266.8 4366.2 83072.8 84314.0 86268.6 87324.6 88044.6 74705.0 76669.6 76859.6 77775.6 78273.0 68353.0 68535.7 68900.7 69232.7 69652.0 8606.7 8940.4 9226.8 9308.2 9360.6 40498.6 41183.8 42121.2 42703.5 44012.0 58408.4 58528.0 60255.0 60700.0 62212.0 11237.5 11422.5 11437.8 11575.7 12015.5 12303.1 12396.6 12439.8 12465.6 12645.6 12159.3 12705.8 12833.2 12985.2 13859.7 13112.8 13461.1 13820.0 14055.2 14427.1 12819.6 12969.7 13503.6 13649.5 13746.3 10188.3 10267.4 10337.3 10417.4 10675.5 10944.5 11020.4 11032.2 11069.8 11235.2 09:29:29 INFO - PROCESS | 18269 | -------- Summary: end -------- 09:29:29 INFO - PROCESS | 18269 | 09:29:30 INFO - TEST-INFO | 18269: exit 0 09:29:30 INFO - TEST-OK | dromaeo_css | took 1117852ms 09:29:30 INFO - TEST-START | kraken 09:29:30 INFO - operating with platform_type : linux_ 09:29:30 INFO - Initialising browser for kraken test... 09:29:30 INFO - TEST-INFO | started process 18572 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf4uckv/profile http://localhost:57841/getInfo.html) 09:29:34 INFO - PROCESS | 18572 | __metrics Screen width/height:1600/1200 09:29:34 INFO - PROCESS | 18572 | colorDepth:24 09:29:34 INFO - PROCESS | 18572 | Browser inner width/height: 1024/697 09:29:34 INFO - PROCESS | 18572 | __metrics 09:29:35 INFO - PROCESS | 18572 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:29:35 INFO - PROCESS | 18572 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:29:35 INFO - PROCESS | 18572 | console.error: PushService: 09:29:35 INFO - PROCESS | 18572 | stateChangeProcessEnqueue: Error transitioning state 09:29:35 INFO - PROCESS | 18572 | UnknownError 09:29:36 INFO - TEST-INFO | 18572: exit 0 09:29:36 INFO - Browser initialized. 09:29:36 INFO - Running cycle 1/1 for kraken test... 09:29:36 INFO - TEST-INFO | started process 18655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf4uckv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:48 INFO - PROCESS | 18655 | RSS: Main: 172212224 09:29:48 INFO - PROCESS | 18655 | 09:29:51 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57841/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:52 INFO - PROCESS | 18655 | RSS: Main: 238886912 09:29:52 INFO - PROCESS | 18655 | 09:29:54 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57841/tests/kraken/driver.html?testName=audio-dft) 09:29:55 INFO - PROCESS | 18655 | RSS: Main: 217112576 09:29:55 INFO - PROCESS | 18655 | 09:29:58 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57841/tests/kraken/driver.html?testName=audio-fft) 09:29:59 INFO - PROCESS | 18655 | RSS: Main: 298147840 09:29:59 INFO - PROCESS | 18655 | 09:30:01 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57841/tests/kraken/driver.html?testName=audio-oscillator) 09:30:01 INFO - PROCESS | 18655 | RSS: Main: 209932288 09:30:01 INFO - PROCESS | 18655 | 09:30:03 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57841/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:04 INFO - PROCESS | 18655 | RSS: Main: 213512192 09:30:04 INFO - PROCESS | 18655 | 09:30:07 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57841/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:08 INFO - PROCESS | 18655 | RSS: Main: 252698624 09:30:08 INFO - PROCESS | 18655 | 09:30:11 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57841/tests/kraken/driver.html?testName=imaging-desaturate) 09:30:12 INFO - PROCESS | 18655 | RSS: Main: 252334080 09:30:12 INFO - PROCESS | 18655 | 09:30:14 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57841/tests/kraken/driver.html?testName=json-parse-financial) 09:30:15 INFO - PROCESS | 18655 | RSS: Main: 250302464 09:30:15 INFO - PROCESS | 18655 | 09:30:16 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57841/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:30:17 INFO - PROCESS | 18655 | RSS: Main: 213188608 09:30:17 INFO - PROCESS | 18655 | 09:30:19 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:30:19 INFO - PROCESS | 18655 | RSS: Main: 220581888 09:30:19 INFO - PROCESS | 18655 | 09:30:21 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:30:22 INFO - PROCESS | 18655 | RSS: Main: 229154816 09:30:22 INFO - PROCESS | 18655 | 09:30:25 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:25 INFO - PROCESS | 18655 | RSS: Main: 238960640 09:30:25 INFO - PROCESS | 18655 | 09:30:28 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:29 INFO - PROCESS | 18655 | RSS: Main: 235016192 09:30:29 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | Cycle 1(1): loaded http://localhost:57841/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57841/tests/kraken/driver.html?testName=ai-astar) 09:30:31 INFO - PROCESS | 18655 | RSS: Main: 286367744 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | __start_tp_report 09:30:31 INFO - PROCESS | 18655 | _x_x_mozilla_page_load 09:30:31 INFO - PROCESS | 18655 | _x_x_mozilla_page_load_details 09:30:31 INFO - PROCESS | 18655 | |i|pagename|runs| 09:30:31 INFO - PROCESS | 18655 | |0;ai-astar;106;113;113;113;114;113;112;114;112;112 09:30:31 INFO - PROCESS | 18655 | |1;audio-beat-detection;146;128;125;159;124;128;123;123;143;123 09:30:31 INFO - PROCESS | 18655 | |2;audio-dft;162;161;163;162;162;162;163;163;162;161 09:30:31 INFO - PROCESS | 18655 | |3;audio-fft;74;75;76;108;76;75;75;75;99;76 09:30:31 INFO - PROCESS | 18655 | |4;audio-oscillator;83;83;105;83;83;82;97;82;82;110 09:30:31 INFO - PROCESS | 18655 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;116;117;117 09:30:31 INFO - PROCESS | 18655 | |6;imaging-darkroom;147;145;145;146;145;145;145;145;145;146 09:30:31 INFO - PROCESS | 18655 | |7;imaging-desaturate;95;95;94;95;95;95;96;95;96;96 09:30:31 INFO - PROCESS | 18655 | |8;json-parse-financial;54;52;52;52;53;53;54;54;53;53 09:30:31 INFO - PROCESS | 18655 | |9;json-stringify-tinderbox;63;64;62;63;64;63;64;64;63;64 09:30:31 INFO - PROCESS | 18655 | |10;stanford-crypto-aes;81;84;87;80;81;81;82;83;80;86 09:30:31 INFO - PROCESS | 18655 | |11;stanford-crypto-ccm;139;138;129;138;143;139;130;149;178;149 09:30:31 INFO - PROCESS | 18655 | |12;stanford-crypto-pbkdf2;165;168;173;164;171;161;166;173;165;163 09:30:31 INFO - PROCESS | 18655 | |13;stanford-crypto-sha256-iterative;63;65;68;65;66;64;65;63;62;64 09:30:31 INFO - PROCESS | 18655 | __end_tp_report 09:30:31 INFO - PROCESS | 18655 | __start_cc_report 09:30:31 INFO - PROCESS | 18655 | _x_x_mozilla_cycle_collect,1660 09:30:31 INFO - PROCESS | 18655 | __end_cc_report 09:30:31 INFO - PROCESS | 18655 | __startTimestamp1481045431296__endTimestamp 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | ------- Summary: start ------- 09:30:31 INFO - PROCESS | 18655 | Number of tests: 14 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:2.30 (2.0%) stddev-sans-first:0.78 09:30:31 INFO - PROCESS | 18655 | Values: 106.0 113.0 113.0 113.0 114.0 113.0 112.0 114.0 112.0 112.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:128.00 stddev:12.62 (9.9%) stddev-sans-first:12.36 09:30:31 INFO - PROCESS | 18655 | Values: 146.0 128.0 125.0 159.0 124.0 128.0 123.0 123.0 143.0 123.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:30:31 INFO - PROCESS | 18655 | Values: 162.0 161.0 163.0 162.0 162.0 162.0 163.0 163.0 162.0 161.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.50 stddev:12.11 (16.0%) stddev-sans-first:12.59 09:30:31 INFO - PROCESS | 18655 | Values: 74.0 75.0 76.0 108.0 76.0 75.0 75.0 75.0 99.0 76.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:83.00 stddev:10.81 (13.0%) stddev-sans-first:11.25 09:30:31 INFO - PROCESS | 18655 | Values: 83.0 83.0 105.0 83.0 83.0 82.0 97.0 82.0 82.0 110.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:30:31 INFO - PROCESS | 18655 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.44 09:30:31 INFO - PROCESS | 18655 | Values: 147.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 146.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 09:30:31 INFO - PROCESS | 18655 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.82 (1.5%) stddev-sans-first:0.78 09:30:31 INFO - PROCESS | 18655 | Values: 54.0 52.0 52.0 52.0 53.0 53.0 54.0 54.0 53.0 53.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:30:31 INFO - PROCESS | 18655 | Values: 63.0 64.0 62.0 63.0 64.0 63.0 64.0 64.0 63.0 64.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:82.50 stddev:2.46 (3.0%) stddev-sans-first:2.55 09:30:31 INFO - PROCESS | 18655 | Values: 81.0 84.0 87.0 80.0 81.0 81.0 82.0 83.0 80.0 86.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:141.00 stddev:13.92 (9.9%) stddev-sans-first:14.68 09:30:31 INFO - PROCESS | 18655 | Values: 139.0 138.0 129.0 138.0 143.0 139.0 130.0 149.0 178.0 149.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:4.20 (2.5%) stddev-sans-first:4.40 09:30:31 INFO - PROCESS | 18655 | Values: 165.0 168.0 173.0 164.0 171.0 161.0 166.0 173.0 165.0 163.0 09:30:31 INFO - PROCESS | 18655 | 09:30:31 INFO - PROCESS | 18655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:65.00 stddev:1.72 (2.6%) stddev-sans-first:1.73 09:30:31 INFO - PROCESS | 18655 | Values: 63.0 65.0 68.0 65.0 66.0 64.0 65.0 63.0 62.0 64.0 09:30:31 INFO - PROCESS | 18655 | -------- Summary: end -------- 09:30:31 INFO - PROCESS | 18655 | 09:30:32 INFO - TEST-INFO | 18655: exit 0 09:30:32 INFO - TEST-OK | kraken | took 61690ms 09:30:32 INFO - SUITE-END | took 1179s 09:30:32 INFO - Completed test suite (00:19:40) 09:30:32 INFO - javascript benchmark 09:30:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3219.3722224166286, 3253.5770349854515, 3259.445894197987, 3289.276359200975, 3312.8343582820867, 5192.0, 5207.479252074791, 5245.26566280721, 5325.547328477074, 5337.279467271917, 5260.421353651095, 5282.498162674539, 5365.54392876605, 5366.382865970415, 5395.244665746799, 4765.952340476586, 4775.331453596499, 4794.736289504076, 4818.975905120469, 4827.8310259140935, 4880.048390563841, 4890.413150421954, 4896.632752543574, 4910.386201724785, 4938.185199442088, 4779.115863565252, 4875.268709693539, 4897.681650692693, 4915.0, 4984.102861484931, 4793.976030119862, 4853.126437241295, 4856.878578035542, 4861.6353773467135, 4864.075825593161, 4819.831305904277, 4843.104025755211, 4851.272309153655, 4851.975740121312, 4854.902901941977, 5253.553447956928, 5291.100512912814, 5292.655977361459, 5299.8410047698635, 5302.337207849019, 5642.576806739511, 5689.032864413019, 5696.202531645573, 5724.055530837449, 5772.913406298909, 5740.052891272935, 5758.0, 5767.942320576824, 5769.24999750034, 5770.711464426804, 5221.503957124067, 5322.574194064504, 5379.731013449312, 5382.273393091921, 5386.5152136307925, 5684.857878553069, 5765.1640512125505, 5776.653400795965, 5780.826575202751, 5847.0, 5578.944210557843, 5584.91622625661, 5588.720563971785, 5590.049691552445, 5590.413006634326, 5640.802571910045, 5656.2929633795775, 5658.0381335173115, 5658.660480371191, 5659.745311460994, 5552.056150454432, 5599.160125981054, 5604.299462567179, 5631.915521267102, 5647.548196144298, 5680.11958146488, 5686.914696279559, 5695.601307908407, 5702.0, 5723.198752174699, 4390.560943905584, 4394.450693663292, 4398.450193725785, 4402.119576084796, 4442.111577684476, 3909.198614284065, 3926.2147570485445, 3931.8034098295543, 3968.0675041365257, 3997.780122093313, 5250.317458730417, 5280.471952804689, 5321.122014867581, 5369.570434365241, 5378.0, 5607.186957891083, 5659.43405659439, 5661.971690141523, 5663.122216056436, 5667.0, 5291.391489978625, 5327.0411325961695, 5338.252644629755, 5339.359276886721, 5386.946130538723, 4831.0820944021225, 4837.32277481153, 4847.709137451764, 4862.149123903303, 4875.926861097087, 5794.159846822272, 5797.884042319214, 5801.158831969426, 5807.82576522705, 5850.1224816277045, 5625.128105143709, 5631.183478395527, 5636.774529018793, 5637.887242255214, 5640.689762063126, 5662.150677398423, 5713.685747283773, 5812.651240925558, 5822.534197264124, 5831.300243970751, 5552.888942221214, 5559.527440167631, 5600.887982240414, 5653.971730141323, 5655.321361436654, 5629.577781666473, 5630.155476678367, 5632.85917852057, 5638.605297629127, 5684.062129748545, 5355.812546560852, 5399.973000135132, 5542.7782888685615, 5556.888862222652, 5576.358718747396, 5566.025945459609, 5571.749271282721, 5580.38615752275, 5618.859528511656, 5622.297212848394, 5466.070767969375, 5486.451354864482, 5495.862603434946, 5507.476789704971, 5507.807226747044, 5467.234587157881, 5502.477264659851, 5596.188552659923, 5607.831765047055, 5654.15187721845, 5405.162199859028, 5449.618526703092, 5458.072127738374, 5459.972700136474, 5462.098753705594], "value": 5241.554302683175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.8710153114017, 577.9017567013607, 589.3399392680199, 624.5409623926413, 641.2081079866367, 30709.0, 39763.2047359053, 40973.180536389256, 41100.99999999996, 43472.347914781305, 41709.0, 42158.789206054, 42202.7889860551, 42241.788791056, 42242.7887860561, 7857.6856925722905, 8409.20112589303, 8562.871556926651, 8563.828723425528, 8567.485950842938, 7948.682052717884, 8053.1141574426765, 8071.273585377315, 9924.454155021507, 10169.949150254239, 10228.43743594102, 10292.639757608486, 10300.587976480932, 10307.278490505667, 10309.63916262931, 10108.898911010943, 10215.387076775418, 10215.489225538731, 10251.487425628764, 10264.486775661187, 9706.0, 9753.414795112245, 9762.170215531689, 9762.90237097627, 9766.218702503782, 38942.415863762064, 39099.41350879739, 39152.0, 39295.0, 39305.60694393048, 3303.4714445688815, 3351.8826841060445, 3473.6179020307745, 3540.327337805809, 3729.925401491982, 29691.70308296911, 30175.547366789517, 30214.395712085636, 30242.395152097055, 30247.243818904484, 4024.476818013669, 4033.3748269018347, 4123.8556650517085, 4146.3780432935, 4271.337942618899, 315703.0, 316453.0, 316977.0, 317369.0, 317401.0, 55157.448425516035, 55620.443795562336, 56011.0, 56059.719701401234, 56064.91642959595, 58480.0, 59025.621797275344, 59100.7044964781, 59112.0, 59131.11303330454, 15343.309551070228, 15864.682706345808, 15869.52391428259, 15885.0, 15892.364305427885, 10443.806800571965, 12326.938365308117, 12481.56314528987, 12488.0, 12495.0, 15734.527964161094, 16489.67020659604, 16720.16399180036, 16822.57943551421, 16944.23750931211, 6664.666766661648, 6677.899831502531, 6784.287649796799, 6811.557248778813, 6906.204386859453, 882.6866462405882, 1001.384148748529, 1009.5406590001531, 1018.5416562546884, 1051.2746205118444, 28469.288267793057, 28487.14538563805, 28736.281592960342, 29161.0, 29322.853385732935, 1041.1774697988656, 1070.4326706845384, 1072.5280876414354, 1134.9546018159347, 1164.1443538998828, 14530.346134423977, 15186.924065379602, 15291.235438228045, 15305.693886122215, 15384.30770615325, 439553.0, 442487.0, 443245.0, 443562.0, 470722.0, 43806.780966094964, 52117.218241726405, 53280.73359633177, 53375.199372009454, 58263.417365826645, 1293.6313150751992, 1311.2132720367895, 1350.0549615269351, 1350.3383342162467, 1361.8910487161004, 27579.0, 28553.714462855936, 29377.441625785705, 29759.25601859971, 29930.25174370658, 48059.75970120127, 48551.271730923356, 48579.757101215684, 48621.756891215315, 48667.269990950874, 392586.0, 396589.0, 397008.0, 397560.0, 399986.0, 27014.729852702007, 27175.456490870467, 27383.315417114733, 27453.450930980867, 27658.3085422858, 43612.34581481217, 43988.91746295468, 44785.0, 44978.0, 45168.0, 36173.81913090523, 38103.618963810004, 38466.2306753869, 38848.0, 39677.0, 37280.0, 37387.89629970297, 37703.81148094242, 38319.0, 38820.41769373518, 38040.61959380481, 38554.80722596369, 38647.03382415462, 38672.22655546929, 38847.08646851554, 32068.5189722159, 32911.8354408236, 33368.33263334768, 34269.0, 34493.482597761555, 26289.0, 26587.0, 26684.599731003662, 26854.59718104269, 26923.90310671977], "value": 19139.685545849392, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.5804151101699, 366.9324447617914, 380.38947489279695, 385.452657226738, 401.6746435387337, 14267.143971361713, 14650.34073466694, 18447.723284150732, 18452.261909523637, 18500.16749246284, 17673.469795906145, 18425.90787046066, 18535.72196417055, 18912.71630925537, 18986.62026759464, 3863.555691095532, 5067.974660126694, 5226.24219488174, 5226.529612334889, 5230.137027390476, 4003.859864904729, 6575.243846957614, 6812.62530560819, 6871.312868713114, 6895.138107736533, 6722.1261236039245, 6881.552699074544, 6882.139732533434, 7151.749688760894, 7213.531120477179, 7046.541974771623, 7233.638318084101, 7270.491065625409, 7277.563346199192, 7286.198518162998, 6991.161060672701, 7022.227554968949, 7034.824129396755, 7037.718491260343, 7038.3665470107935, 23347.649785253234, 24026.519469610685, 24295.757042429526, 25068.0, 25256.242312730647, 2412.1678021082876, 2447.963280550793, 2515.333436639292, 2586.482703459316, 2614.6993095794037, 14332.0, 15557.844421555752, 15601.843981560152, 15768.369265229492, 16186.514404567883, 2038.2051000109968, 2089.185217765073, 2104.116271166114, 2104.2529901884764, 2138.0485683870684, 88386.0, 88519.91840652797, 88650.5567472165, 88820.0, 88881.55559222163, 16714.4149954751, 16761.41335053267, 17144.314227430765, 17179.196184400458, 17336.65326693459, 29904.0, 29985.0, 30004.69995300063, 30075.992400759747, 30205.69794302073, 9095.727128186165, 9215.308851836138, 9358.953205233931, 9552.092551207625, 9568.808623827623, 7583.2416758323725, 7628.160902300742, 7694.692212311445, 7879.803004924923, 7926.920730792734, 8464.534450605153, 8479.872801907977, 8532.317414606816, 8546.78633034179, 8582.326446342904, 3356.5300857879915, 3532.5693094296853, 3594.299111673251, 3628.528291322164, 3649.9817500912327, 834.9874751878726, 928.6192661009089, 985.1773768902902, 1004.0160642570322, 1017.2523195451338, 17271.39550115765, 17872.195751191226, 18384.17271222798, 18461.5384615383, 18844.0, 964.9228061755043, 975.5463709375175, 975.9512024398751, 977.39401571025, 986.7335819328885, 9718.951405242928, 10344.5344959477, 10422.426766527913, 10426.0, 10430.947845260725, 440770.0, 442105.0, 445296.0, 452901.0, 455338.0, 83871.0, 84925.0, 86613.0, 86900.0, 86909.56545217233, 950.8193443245899, 957.8802649668792, 967.9225661947028, 968.9127978481971, 971.7376308396834, 15635.140067295953, 15962.920185398998, 16069.839301607302, 16554.66890662156, 16825.74761378556, 37444.81277593595, 38677.80661096676, 38726.0, 39298.0, 40925.386119207644, 51352.74323628358, 51960.0, 52627.0, 53191.0, 53343.73328133334, 7161.9641901790155, 8316.126806685212, 8373.95813020931, 8415.579221039046, 8582.828343432971, 12103.576374826838, 12238.388080596113, 12455.501779929064, 12488.12583119173, 13144.0799144059, 10936.398498082683, 10979.694264204607, 11010.229283950366, 11245.0, 11265.0, 6972.9302706972285, 11024.283421577571, 11104.500297486471, 11271.154663400113, 11293.378864162221, 11274.210805243882, 11350.602728904449, 11391.145664075393, 11425.771484570429, 11578.131640126856, 7687.231276872268, 7747.690092396241, 7874.527528348317, 7923.445358825057, 7987.642100842987, 9550.953006247353, 11041.33751974884, 11204.551817927191, 11562.132840036862, 11630.825537617111], "value": 9870.398327937855, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.4453751693047, 1401.257333613185, 1410.2243765928736, 1496.4837131189736, 1699.2693141948964, 7124.643767811608, 7570.356519695825, 7765.572893490863, 7769.261920117594, 7914.366850651948, 7807.258310460511, 7874.370050395969, 7876.133625301212, 7890.487118337319, 7894.763157105282, 2020.0707674469688, 2084.718562993999, 2228.888555572219, 2274.37454699958, 2276.35123989663, 2256.3907744908865, 2318.5710643530915, 2326.639370897514, 2330.3242059802637, 2344.882755862209, 2254.966175507369, 2274.920377786778, 2286.2683941138757, 2287.7597852225526, 2306.0660432524855, 2244.5735310291075, 2258.5482903419384, 2264.547090581874, 2266.4220623741007, 2266.8299877509253, 2222.5777102350503, 2267.693861328716, 2269.4439862233694, 2270.0919632147106, 2271.670607761882, 8075.959620201861, 8124.22819832115, 8161.877571836427, 8193.221643943816, 8196.180381961816, 4978.551930326288, 5101.872453188663, 5103.923441148386, 5112.335396398482, 5118.38579370478, 3068.447679417704, 3262.429074911881, 3264.6735326467165, 3273.4271502486968, 3279.376918385523, 963.3160456076195, 1045.4929359260752, 1046.884842667306, 1934.4390126863193, 1972.102693274571, 10883.510242039127, 11017.063549598293, 11081.72295692598, 11092.722681932935, 11104.666859994133, 4156.418101465737, 4438.889027774331, 4477.350784136282, 4488.147252022105, 4497.77511124449, 8788.472691638523, 8791.868121978176, 8800.47197168172, 8802.559872006374, 8816.735497935073, 5060.620453465931, 5084.720340381315, 5089.6691715038405, 5095.286659867622, 5101.362329708786, 4639.6313087638555, 4674.158651442773, 4729.95270047302, 4747.7626118693925, 4770.928436073462, 3512.8946131616094, 3553.8400771965325, 3560.169665116239, 3605.369060414417, 3638.799866007342, 1111.8554587903682, 1125.7579620381657, 1152.1646806065535, 1155.2259985809528, 1155.6186458468685, 786.6420778545748, 856.5225185745892, 881.4226681523612, 915.190056799737, 949.3164921256684, 5512.91730624041, 5597.916031259534, 5676.0, 5771.163181338682, 5926.259217597801, 898.6116450084635, 900.2752784008804, 930.497531333086, 954.9379290346105, 973.3867663372031, 3794.4687743715904, 3944.980275098606, 3988.4216788565495, 3993.6405723485027, 4020.115574573589, 9721.270904682176, 10092.192624590016, 10099.394036357842, 10116.797664046679, 10118.190544756402, 10020.0, 10027.899721002697, 10032.598696052222, 10042.297039207186, 10076.748081298027, 7548.962255188689, 7617.6572054257695, 7687.730929417444, 7700.807479813049, 7747.806304842311, 5225.085610018307, 5576.386597474194, 5603.7758489659955, 5679.119736440858, 5856.648601083778, 8958.68644597436, 9017.729468115967, 9077.591508382007, 9123.954380228057, 9126.08739126082, 9323.114304141096, 9380.437173769596, 9383.624655013722, 9422.246220302497, 9498.620055197714, 6157.291911430243, 6320.905186422299, 6331.145295385064, 6449.903251451138, 6452.9677351612945, 7596.392288616787, 7726.304632583095, 7731.806704832424, 7775.066991960774, 7945.6027198640995, 6945.652717364213, 7068.611226382598, 7111.0, 7112.288771122846, 7369.742059028123, 6590.90113648286, 6635.104260924713, 6700.93299067003, 6906.412954898889, 7124.786256412316, 6476.384743449365, 6631.403173714389, 6638.0374845648075, 6639.070530125689, 6644.966775166094, 4725.36207611968, 4849.587785038311, 4884.877878053077, 5017.071841709296, 5169.53474187325, 4893.7063776173545, 4953.108440480604, 5087.542121209109, 5146.382434107931, 5192.714400707997], "value": 4362.32670615665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.313747792511, 313.39827531140213, 316.82257935556106, 318.4745170468728, 320.54162547556984, 5134.589232861372, 5188.974055129728, 5254.3432070991075, 5317.361916570012, 5345.946540534598, 5026.82406115786, 5119.6672216305915, 5142.511461411169, 5142.6914385136915, 5156.2265660151, 4029.6776257899446, 4039.2325458163, 4041.3533834586474, 4042.4946881639794, 4061.4110953911663, 3892.0853599404118, 3947.6841852651864, 4066.6949978751563, 4079.8164082616204, 4079.9796001019954, 3828.8659896903614, 4021.758694478326, 4045.797710114497, 4051.372037334218, 4083.3874918762276, 4048.4939382577177, 4052.797360131982, 4053.97973010136, 4068.511778586559, 4076.30702780528, 3588.5155504006884, 3591.2278860045217, 3600.71194304455, 3602.0814692253307, 3607.6572725591027, 4892.6575139740235, 5027.260143221953, 5040.521150490697, 5046.823361182378, 5140.3831540215415, 4537.954620453786, 4542.545745425431, 4552.658550608718, 4561.589456948891, 4571.1771881061395, 5393.541548968343, 5404.756785944603, 5429.375621803503, 5496.80761173361, 5498.202760599731, 3685.4471829225563, 3820.777351247602, 3825.8469661213517, 3857.324968130566, 3861.9806900965596, 5616.326040875121, 5637.548996080303, 5643.351014633287, 5646.362554859086, 5656.2929633795775, 3951.5060617422823, 3960.7821569813937, 3967.821448034845, 3975.185087057146, 3985.601439855991, 3934.272159650474, 3999.34010888206, 4030.375291829771, 4053.3717273822604, 4053.6959728020515, 4606.608438282717, 4612.930806037912, 4615.423072115986, 4617.930731039037, 4629.0973260214605, 5456.945430545644, 5473.909264384161, 5474.370447398521, 5491.7254137292975, 5502.202180683858, 1284.7302066566126, 1330.1287656584952, 1354.9051566390258, 1355.5323413422354, 1357.8234829472106, 901.3555307954713, 929.6560272699145, 933.8926023507248, 939.2392162348519, 940.7977284883783, 5069.9746501267255, 5095.210242412432, 5109.744512774347, 5113.74431278442, 5134.589232861362, 5377.78488860449, 5393.78424863009, 5399.433059528771, 5400.568849254951, 5407.80939245839, 4980.0, 5077.629040159136, 5128.640995130379, 5141.948580514222, 5146.459621739738, 2868.655761308656, 2879.481693295227, 2885.5960165576835, 2894.6381702287213, 2894.7394734473996, 5587.580931430159, 5600.35595906476, 5600.507774040513, 5616.213730077792, 5651.180578816049, 5348.224507446398, 5452.047265732313, 5479.178123281539, 5519.834404967857, 5520.861978450491, 5593.496585307342, 5602.831915042637, 5602.915956260659, 5615.185798059259, 5628.831135065954, 5254.973725131503, 5418.403975562606, 5429.393890932453, 5472.616916815944, 5486.725663716718, 5344.652597581145, 5358.517733404013, 5368.624196306221, 5371.865703357447, 5374.704391258519, 5539.0, 5548.334199896039, 5550.084236100998, 5611.438856114356, 5645.802396916089, 3727.9189035179493, 3843.8660595125066, 3857.9035524112123, 3860.613938606117, 3875.7286989910435, 5646.108553634578, 5647.88704225905, 5655.830325090254, 5672.290963629546, 5675.375708671961, 5508.504234618904, 5525.806596769094, 5534.39121696605, 5559.19391688211, 5575.856949325297, 5321.787128514816, 5454.3454785426, 5518.586106042143, 5556.555475561864, 5576.442355764553, 5447.591430642797, 5463.470228336072, 5463.644863083887, 5465.098258787256, 5465.234867118527, 5529.4470552944385, 5552.972235138798, 5554.555815488083, 5562.694051827189, 5586.581006424616, 5332.9733351333, 5396.757145928521, 5402.621816472809, 5420.241166236651, 5453.14593038353], "value": 4115.311369905639, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.8369674974753, 737.5464089584906, 759.9012128423304, 785.9017622797151, 802.0856223904748, 15403.306851191695, 16204.94667846589, 16217.108059056747, 16231.431899883506, 16331.428400006002, 14537.127772333668, 14656.780148297785, 14834.55496335109, 14837.109773413602, 14837.925810370933, 665.9101021362127, 684.8527566573185, 767.6238643064913, 781.5427974634831, 789.4039999800171, 792.1207459719707, 792.3581898662102, 794.9046114466272, 795.0499153511556, 797.7287722174459, 853.8847255620504, 855.2772906893696, 855.3456605696647, 855.4054931822386, 855.632078206371, 843.2621456225802, 852.9786755331166, 853.14685314685, 853.3514528958036, 853.4111463090447, 510.60428168169966, 519.8050730975884, 592.1946153231602, 599.8590331272147, 612.5589575505674, 14754.778678319833, 14842.332095055748, 14842.628934276621, 14857.331420086122, 14857.47998820047, 13810.309484525835, 13812.723745525032, 13937.442502299897, 13979.440822367093, 14061.0, 12043.337616431092, 12153.210041347284, 12187.32969686667, 12449.502019919193, 13069.215847049207, 4182.163567286555, 4220.767857767822, 4315.590018948226, 4421.226285400042, 4425.623821975136, 76670.61664691697, 76753.84869227021, 77404.22595774083, 77555.61222193853, 78560.60719696365, 10348.120409765253, 10420.426876521864, 10724.89275107239, 10819.621313254156, 10828.012769353614, 20559.177632894516, 20944.952752362322, 21075.68386474204, 21188.682169767464, 21390.0, 7712.267334603203, 7771.922280777233, 7829.64766585505, 7925.960370198228, 8413.789655258668, 4302.978485107555, 4400.053988392511, 4403.163398954189, 4447.08834688888, 4499.977500112478, 3067.9846600766855, 3126.374725054998, 3126.8280244586767, 3129.85915633797, 3136.247300647828, 552.7512619321241, 563.1918197386763, 583.4486410342271, 584.0625795598089, 590.4331841432187, 602.4367216652356, 613.3406587918006, 638.9297177310492, 640.8013515810114, 669.8794217040881, 8846.46921184731, 9386.85919711205, 9469.715908522756, 9512.47681377517, 9624.181944534654, 503.54177698294376, 523.5785192919736, 539.323149447441, 610.8198081565948, 638.3871483376012, 4027.979860100739, 4185.895352616209, 4216.254325731929, 4266.829326826892, 4366.235908715962, 83072.78517400194, 84314.0, 86268.56865715631, 87324.56337718398, 88044.5597772007, 74705.0, 76669.6166519175, 76859.61570192114, 77775.61112194402, 78273.0, 68353.0, 68535.65732171308, 68900.65549672219, 69232.65383673049, 69652.0, 8606.65573377083, 8940.418872773249, 9226.815463690822, 9308.177351660011, 9360.578773955325, 40498.59501404948, 41183.7940810294, 42121.157576847676, 42703.505377311645, 44012.019459125055, 58408.415915842, 58528.0, 60255.0, 60700.0, 62212.0, 11237.494312755782, 11422.543098276308, 11437.828432573351, 11575.652730418102, 12015.519379224734, 12303.138780285293, 12396.628101156979, 12439.813402799145, 12465.626031219077, 12645.620631381073, 12159.331236782062, 12705.809412859, 12833.165844220095, 12985.155964862253, 13859.722805544034, 13112.788214248369, 13461.117196057112, 13820.021325662867, 14055.22696251716, 14427.062240954305, 12819.615411537296, 12969.675758106123, 13503.59489215325, 13649.454021839016, 13746.312684365941, 10188.286819922534, 10267.383956962607, 10337.276390652582, 10417.374957502574, 10675.519601618102, 10944.45277736094, 11020.393878336448, 11032.172587055842, 11069.778604428027, 11235.15736319763], "value": 7025.260718069438, "unit": "score"}], "name": "dromaeo_css", "value": 7070.1201890864, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 114.0, 112.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 128.0, 125.0, 159.0, 124.0, 128.0, 123.0, 123.0, 143.0, 123.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 162.0, 162.0, 162.0, 163.0, 163.0, 162.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 108.0, 76.0, 75.0, 75.0, 75.0, 99.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 105.0, 83.0, 83.0, 82.0, 97.0, 82.0, 82.0, 110.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 52.0, 53.0, 53.0, 54.0, 54.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 63.0, 64.0, 63.0, 64.0, 64.0, 63.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 87.0, 80.0, 81.0, 81.0, 82.0, 83.0, 80.0, 86.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 129.0, 138.0, 143.0, 139.0, 130.0, 149.0, 178.0, 149.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 173.0, 164.0, 171.0, 161.0, 166.0, 173.0, 165.0, 163.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 68.0, 65.0, 66.0, 64.0, 65.0, 63.0, 62.0, 64.0], "value": 64.5, "unit": "score"}], "name": "kraken", "value": 1507.2000000000003, "alertThreshold": 2.0}]} 09:30:33 INFO - Return code: 0 09:30:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:30:33 INFO - # TBPL SUCCESS # 09:30:33 INFO - Running post-action listener: _resource_record_post_action 09:30:33 INFO - [mozharness: 2016-12-06 17:30:33.113007Z] Finished run-tests step (success) 09:30:33 INFO - Running post-run listener: _resource_record_post_run 09:30:33 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392622080; Read time: 12; Write time: 244328 09:30:33 INFO - TinderboxPrint: CPU usage
13.1% 09:30:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:30:33 INFO - TinderboxPrint: I/O write bytes / time
392,622,080 / 244,328 09:30:33 INFO - TinderboxPrint: CPU idle
8,296.3 (86.9%) 09:30:33 INFO - TinderboxPrint: CPU user
1,204.7 (12.6%) 09:30:33 INFO - TinderboxPrint: Swap in / out
0 / 0 09:30:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103796736; Read time: 0; Write time: 57556 09:30:33 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 288825344; Read time: 0; Write time: 186772 09:30:33 INFO - Running post-run listener: _upload_blobber_files 09:30:33 INFO - Blob upload gear active. 09:30:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:30:34 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:30:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:30:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:37 INFO - (blobuploader) - INFO - Done attempting. 09:30:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:30:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:30:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:38 INFO - (blobuploader) - INFO - Done attempting. 09:30:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:30:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:39 INFO - (blobuploader) - INFO - Done attempting. 09:30:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:39 INFO - Return code: 0 09:30:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c19b27ada89ac7bb51bc4fc989aa242aab07f238445be9e8956b4f6be93522ebae85b9def4a65e155a4ed974d1086b58ae050c269e66afb968d58d6235b49c56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/748eabe37381f8c9505fe42644acd51998c235ca2b34265ebac9fa81c82ffe08ef55ed084ab3e8a3dae4b36a443eba40e62b3de0549f0ee381784a9b79f17352", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c7e2fcd498eeaf82ad725c37429c7ca3d89614fd0f28927f356fc7470063b0872500c7488a2cce89646b10fa03140e49e296042aeec172d00821cd051995b0"} 09:30:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:39 INFO - Contents: 09:30:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c19b27ada89ac7bb51bc4fc989aa242aab07f238445be9e8956b4f6be93522ebae85b9def4a65e155a4ed974d1086b58ae050c269e66afb968d58d6235b49c56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/748eabe37381f8c9505fe42644acd51998c235ca2b34265ebac9fa81c82ffe08ef55ed084ab3e8a3dae4b36a443eba40e62b3de0549f0ee381784a9b79f17352", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c7e2fcd498eeaf82ad725c37429c7ca3d89614fd0f28927f356fc7470063b0872500c7488a2cce89646b10fa03140e49e296042aeec172d00821cd051995b0"} 09:30:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:39 INFO - Copying logs to upload dir... 09:30:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.891745 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-06 09:30:39.413436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 09:30:39.418248) ========= 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-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c19b27ada89ac7bb51bc4fc989aa242aab07f238445be9e8956b4f6be93522ebae85b9def4a65e155a4ed974d1086b58ae050c269e66afb968d58d6235b49c56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/748eabe37381f8c9505fe42644acd51998c235ca2b34265ebac9fa81c82ffe08ef55ed084ab3e8a3dae4b36a443eba40e62b3de0549f0ee381784a9b79f17352", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c7e2fcd498eeaf82ad725c37429c7ca3d89614fd0f28927f356fc7470063b0872500c7488a2cce89646b10fa03140e49e296042aeec172d00821cd051995b0"} build_url:https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/QWGcBsUiTOCNFnaGuyOTAA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c19b27ada89ac7bb51bc4fc989aa242aab07f238445be9e8956b4f6be93522ebae85b9def4a65e155a4ed974d1086b58ae050c269e66afb968d58d6235b49c56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/748eabe37381f8c9505fe42644acd51998c235ca2b34265ebac9fa81c82ffe08ef55ed084ab3e8a3dae4b36a443eba40e62b3de0549f0ee381784a9b79f17352", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21c7e2fcd498eeaf82ad725c37429c7ca3d89614fd0f28927f356fc7470063b0872500c7488a2cce89646b10fa03140e49e296042aeec172d00821cd051995b0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 09:30:39.446925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:30:39.447338) ========= 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-1481043744.535647-1153519806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004591 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 09:30:39.527540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 09:30:39.530886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 09:30:39.531238) ========= ========= Total master_lag: 0.19 =========