builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1482679862.24 results: success (0) buildid: 20161225064336 builduid: af2bb175bf074c2f924f65dea26e42c9 revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.239228) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.239699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.240038) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.258923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.259412) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-25 07:31:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-12-25 07:31:02 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085125 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.362811) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.363151) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018910 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.415291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:02.418018) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ea0c495d3b2318287bffe1121e0e33d74427143 --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 5ea0c495d3b2318287bffe1121e0e33d74427143 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-25 07:31:02,870 truncating revision to first 12 chars 2016-12-25 07:31:02,870 Setting DEBUG logging. 2016-12-25 07:31:02,870 attempt 1/10 2016-12-25 07:31:02,870 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ea0c495d3b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 07:31:03,169 unpacking tar archive at: mozilla-central-5ea0c495d3b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.617409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:03.052375) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:03.052770) ========= script_repo_revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:03.053224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:03.053528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 07:31:03.064296) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-25 07:31:03.064739) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:31:03 INFO - MultiFileLogger online at 20161225 07:31:03 in /builds/slave/test 07:31:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:31:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:03 INFO - {'append_to_log': False, 07:31:03 INFO - 'base_work_dir': '/builds/slave/test', 07:31:03 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:31:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:03 INFO - 'branch': 'Firefox-Non-PGO', 07:31:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:03 INFO - 'config_files': ('talos/linux_config.py',), 07:31:03 INFO - 'default_actions': ('clobber', 07:31:03 INFO - 'read-buildbot-config', 07:31:03 INFO - 'download-and-extract', 07:31:03 INFO - 'populate-webroot', 07:31:03 INFO - 'create-virtualenv', 07:31:03 INFO - 'install', 07:31:03 INFO - 'run-tests'), 07:31:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:03 INFO - 'download_minidump_stackwalk': True, 07:31:03 INFO - 'download_symbols': 'ondemand', 07:31:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:03 INFO - 'installer_path': 'installer.exe', 07:31:03 INFO - 'log_level': 'info', 07:31:03 INFO - 'log_name': 'talos', 07:31:03 INFO - 'log_to_console': True, 07:31:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:31:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:31:03 INFO - 'opt_config_files': (), 07:31:03 INFO - 'pip_index': False, 07:31:03 INFO - 'sps_profile': False, 07:31:03 INFO - 'sps_profile_interval': 0, 07:31:03 INFO - 'suite': 'dromaeojs', 07:31:03 INFO - 'system_bits': '32', 07:31:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:03 INFO - 'title': 'talos-linux64-ix-038', 07:31:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:03 INFO - 'use_talos_json': True, 07:31:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:03 INFO - 'work_dir': 'build'} 07:31:03 INFO - [mozharness: 2016-12-25 15:31:03.612974Z] Running clobber step. 07:31:03 INFO - Running pre-action listener: _resource_record_pre_action 07:31:03 INFO - Running main action method: clobber 07:31:03 INFO - rmtree: /builds/slave/test/build 07:31:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:04 INFO - Running post-action listener: _resource_record_post_action 07:31:04 INFO - [mozharness: 2016-12-25 15:31:04.070322Z] Finished clobber step (success) 07:31:04 INFO - [mozharness: 2016-12-25 15:31:04.070423Z] Running read-buildbot-config step. 07:31:04 INFO - Running pre-action listener: _resource_record_pre_action 07:31:04 INFO - Running main action method: read_buildbot_config 07:31:04 INFO - Using buildbot properties: 07:31:04 INFO - { 07:31:04 INFO - "project": "", 07:31:04 INFO - "product": "firefox", 07:31:04 INFO - "script_repo_revision": "production", 07:31:04 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:31:04 INFO - "repository": "", 07:31:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:31:04 INFO - "buildid": "20161225064336", 07:31:04 INFO - "pgo_build": "False", 07:31:04 INFO - "basedir": "/builds/slave/test", 07:31:04 INFO - "buildnumber": 356, 07:31:04 INFO - "slavename": "talos-linux64-ix-038", 07:31:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:31:04 INFO - "platform": "ubuntu64_hw", 07:31:04 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:31:04 INFO - "branch": "mozilla-central", 07:31:04 INFO - "repo_path": "mozilla-central", 07:31:04 INFO - "revision": "5ea0c495d3b2318287bffe1121e0e33d74427143", 07:31:04 INFO - "stage_platform": "linux64", 07:31:04 INFO - "builduid": "af2bb175bf074c2f924f65dea26e42c9", 07:31:04 INFO - "slavebuilddir": "test" 07:31:04 INFO - } 07:31:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:31:04 INFO - Running post-action listener: _resource_record_post_action 07:31:04 INFO - [mozharness: 2016-12-25 15:31:04.072827Z] Finished read-buildbot-config step (success) 07:31:04 INFO - [mozharness: 2016-12-25 15:31:04.072916Z] Running download-and-extract step. 07:31:04 INFO - Running pre-action listener: _resource_record_pre_action 07:31:04 INFO - Running main action method: download_and_extract 07:31:04 INFO - mkdir: /builds/slave/test/build/tests 07:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:04 INFO - trying https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:31:04 INFO - Downloading https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:31:04 INFO - Downloaded 1361 bytes. 07:31:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:31:04 INFO - Using the following test package requirements: 07:31:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:31:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:31:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:31:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'jsshell-linux-x86_64.zip'], 07:31:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:31:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:31:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:31:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:31:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:31:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:31:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:31:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:31:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:31:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:31:04 INFO - Fetch https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:31:05 INFO - Expected file size: 20241340 07:31:05 INFO - Obtained file size: 20241340 07:31:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:31:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:31:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:31:09 INFO - Fetch https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:31:10 INFO - Expected file size: 20241340 07:31:10 INFO - Obtained file size: 20241340 07:31:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:31:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:31:14 INFO - Fetch https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:31:15 INFO - Expected file size: 14409776 07:31:15 INFO - Obtained file size: 14409776 07:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:15 INFO - trying https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:31:15 INFO - Downloading https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:31:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:31:16 INFO - Downloaded 55896808 bytes. 07:31:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:31:16 INFO - mkdir: /builds/slave/test/properties 07:31:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:16 INFO - Contents: 07:31:16 INFO - build_url:https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:31:17 INFO - Running post-action listener: _resource_record_post_action 07:31:17 INFO - Running post-action listener: set_extra_try_arguments 07:31:17 INFO - [mozharness: 2016-12-25 15:31:17.482201Z] Finished download-and-extract step (success) 07:31:17 INFO - [mozharness: 2016-12-25 15:31:17.482426Z] Running populate-webroot step. 07:31:17 INFO - Running pre-action listener: _resource_record_pre_action 07:31:17 INFO - Running main action method: populate_webroot 07:31:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:31:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:31:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:31:17 INFO - '--tppagecycles', 07:31:17 INFO - '7'], 07:31:17 INFO - 'tests': ['tsvgm']}}, 07:31:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:31:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:31:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:31:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:31:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['damp', 'tps']}, 07:31:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'tests': ['damp', 'tps']}, 07:31:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['dromaeo_dom']}, 07:31:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:31:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['basic_compositor_video']}, 07:31:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:31:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['a11yr', 07:31:17 INFO - 'ts_paint', 07:31:17 INFO - 'tpaint', 07:31:17 INFO - 'sessionrestore', 07:31:17 INFO - 'sessionrestore_no_auto_restore', 07:31:17 INFO - 'tabpaint']}, 07:31:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['tsvgx', 07:31:17 INFO - 'tsvgr_opacity', 07:31:17 INFO - 'tart', 07:31:17 INFO - 'tscrollx', 07:31:17 INFO - 'cart', 07:31:17 INFO - 'tsvg_static']}, 07:31:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:31:17 INFO - 'tsvgr_opacity', 07:31:17 INFO - 'tart', 07:31:17 INFO - 'tscrollx', 07:31:17 INFO - 'cart', 07:31:17 INFO - 'tsvg_static']}, 07:31:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'talos_options': ['--disable-e10s'], 07:31:17 INFO - 'tests': ['tp5o']}, 07:31:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:31:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'talos_options': ['--disable-e10s', 07:31:17 INFO - '--xperf_path', 07:31:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:17 INFO - 'tests': ['tp5n']}, 07:31:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:31:17 INFO - 'talos_options': ['--xperf_path', 07:31:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:17 INFO - 'tests': ['tp5n']}}, 07:31:17 INFO - 'talos.zip': {'path': '', 07:31:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:31:17 INFO - Running post-action listener: _resource_record_post_action 07:31:17 INFO - [mozharness: 2016-12-25 15:31:17.501945Z] Finished populate-webroot step (success) 07:31:17 INFO - [mozharness: 2016-12-25 15:31:17.502032Z] Running create-virtualenv step. 07:31:17 INFO - Running pre-action listener: _resource_record_pre_action 07:31:17 INFO - Running main action method: create_virtualenv 07:31:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:31:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:31:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:31:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:31:17 INFO - Using real prefix '/usr' 07:31:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:31:18 INFO - Installing distribute.............................................................................................................................................................................................done. 07:31:20 INFO - Installing pip.................done. 07:31:20 INFO - Return code: 0 07:31:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:31:20 INFO - Reading from file tmpfile_stdout 07:31:20 INFO - Output received: 07:31:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:31:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:31:20 INFO - Using env: {'DISPLAY': ':0', 07:31:20 INFO - 'HOME': '/home/cltbld', 07:31:20 INFO - 'LANG': 'en_US.UTF-8', 07:31:20 INFO - 'LANGUAGE': 'en_US:en', 07:31:20 INFO - 'LOGNAME': 'cltbld', 07:31:20 INFO - 'MAIL': '/var/mail/cltbld', 07:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:20 INFO - 'MOZ_NO_REMOTE': '1', 07:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:20 INFO - 'NO_EM_RESTART': '1', 07:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:20 INFO - 'PWD': '/builds/slave/test', 07:31:20 INFO - 'SHELL': '/bin/bash', 07:31:20 INFO - 'SHLVL': '1', 07:31:20 INFO - 'TERM': 'linux', 07:31:20 INFO - 'TMOUT': '86400', 07:31:20 INFO - 'USER': 'cltbld', 07:31:20 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:20 INFO - '_': '/tools/buildbot/bin/python'} 07:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:31:20 INFO - Cleaning up... 07:31:20 INFO - Return code: 0 07:31:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:31:20 INFO - Using env: {'DISPLAY': ':0', 07:31:20 INFO - 'HOME': '/home/cltbld', 07:31:20 INFO - 'LANG': 'en_US.UTF-8', 07:31:20 INFO - 'LANGUAGE': 'en_US:en', 07:31:20 INFO - 'LOGNAME': 'cltbld', 07:31:20 INFO - 'MAIL': '/var/mail/cltbld', 07:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:20 INFO - 'MOZ_NO_REMOTE': '1', 07:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:20 INFO - 'NO_EM_RESTART': '1', 07:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:20 INFO - 'PWD': '/builds/slave/test', 07:31:20 INFO - 'SHELL': '/bin/bash', 07:31:20 INFO - 'SHLVL': '1', 07:31:20 INFO - 'TERM': 'linux', 07:31:20 INFO - 'TMOUT': '86400', 07:31:20 INFO - 'USER': 'cltbld', 07:31:20 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:20 INFO - '_': '/tools/buildbot/bin/python'} 07:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:20 INFO - Downloading/unpacking psutil>=3.1.1 07:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:22 INFO - Installing collected packages: psutil 07:31:22 INFO - Running setup.py install for psutil 07:31:23 INFO - building 'psutil._psutil_linux' extension 07:31:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:31:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:31:23 INFO - building 'psutil._psutil_posix' extension 07:31:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:31:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:31:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:23 INFO - Successfully installed psutil 07:31:23 INFO - Cleaning up... 07:31:23 INFO - Return code: 0 07:31:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:31:23 INFO - Using env: {'DISPLAY': ':0', 07:31:23 INFO - 'HOME': '/home/cltbld', 07:31:23 INFO - 'LANG': 'en_US.UTF-8', 07:31:23 INFO - 'LANGUAGE': 'en_US:en', 07:31:23 INFO - 'LOGNAME': 'cltbld', 07:31:23 INFO - 'MAIL': '/var/mail/cltbld', 07:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:23 INFO - 'MOZ_NO_REMOTE': '1', 07:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:23 INFO - 'NO_EM_RESTART': '1', 07:31:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:23 INFO - 'PWD': '/builds/slave/test', 07:31:23 INFO - 'SHELL': '/bin/bash', 07:31:23 INFO - 'SHLVL': '1', 07:31:23 INFO - 'TERM': 'linux', 07:31:23 INFO - 'TMOUT': '86400', 07:31:23 INFO - 'USER': 'cltbld', 07:31:23 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:23 INFO - '_': '/tools/buildbot/bin/python'} 07:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:31:25 INFO - Installing collected packages: mozsystemmonitor 07:31:25 INFO - Running setup.py install for mozsystemmonitor 07:31:25 INFO - Successfully installed mozsystemmonitor 07:31:25 INFO - Cleaning up... 07:31:25 INFO - Return code: 0 07:31:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:31:25 INFO - Using env: {'DISPLAY': ':0', 07:31:25 INFO - 'HOME': '/home/cltbld', 07:31:25 INFO - 'LANG': 'en_US.UTF-8', 07:31:25 INFO - 'LANGUAGE': 'en_US:en', 07:31:25 INFO - 'LOGNAME': 'cltbld', 07:31:25 INFO - 'MAIL': '/var/mail/cltbld', 07:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:25 INFO - 'MOZ_NO_REMOTE': '1', 07:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:25 INFO - 'NO_EM_RESTART': '1', 07:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:25 INFO - 'PWD': '/builds/slave/test', 07:31:25 INFO - 'SHELL': '/bin/bash', 07:31:25 INFO - 'SHLVL': '1', 07:31:25 INFO - 'TERM': 'linux', 07:31:25 INFO - 'TMOUT': '86400', 07:31:25 INFO - 'USER': 'cltbld', 07:31:25 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:25 INFO - '_': '/tools/buildbot/bin/python'} 07:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:26 INFO - Downloading/unpacking jsonschema==2.5.1 07:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:31:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:28 INFO - Downloading functools32-3.2.3-2.tar.gz 07:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:31:28 INFO - warning: no files found matching '*.txt' 07:31:28 INFO - no previously-included directories found matching 'build' 07:31:28 INFO - no previously-included directories found matching 'dist' 07:31:28 INFO - no previously-included directories found matching '.git*' 07:31:28 INFO - Installing collected packages: jsonschema, functools32 07:31:28 INFO - Running setup.py install for functools32 07:31:28 INFO - warning: no files found matching '*.txt' 07:31:28 INFO - no previously-included directories found matching 'build' 07:31:28 INFO - no previously-included directories found matching 'dist' 07:31:28 INFO - no previously-included directories found matching '.git*' 07:31:28 INFO - Successfully installed jsonschema functools32 07:31:28 INFO - Cleaning up... 07:31:28 INFO - Return code: 0 07:31:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:31:28 INFO - Using env: {'DISPLAY': ':0', 07:31:28 INFO - 'HOME': '/home/cltbld', 07:31:28 INFO - 'LANG': 'en_US.UTF-8', 07:31:28 INFO - 'LANGUAGE': 'en_US:en', 07:31:28 INFO - 'LOGNAME': 'cltbld', 07:31:28 INFO - 'MAIL': '/var/mail/cltbld', 07:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:28 INFO - 'MOZ_NO_REMOTE': '1', 07:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:28 INFO - 'NO_EM_RESTART': '1', 07:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:28 INFO - 'PWD': '/builds/slave/test', 07:31:28 INFO - 'SHELL': '/bin/bash', 07:31:28 INFO - 'SHLVL': '1', 07:31:28 INFO - 'TERM': 'linux', 07:31:28 INFO - 'TMOUT': '86400', 07:31:28 INFO - 'USER': 'cltbld', 07:31:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:28 INFO - '_': '/tools/buildbot/bin/python'} 07:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:31:28 INFO - Cleaning up... 07:31:28 INFO - Return code: 0 07:31:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:31:28 INFO - Using env: {'DISPLAY': ':0', 07:31:28 INFO - 'HOME': '/home/cltbld', 07:31:28 INFO - 'LANG': 'en_US.UTF-8', 07:31:28 INFO - 'LANGUAGE': 'en_US:en', 07:31:28 INFO - 'LOGNAME': 'cltbld', 07:31:28 INFO - 'MAIL': '/var/mail/cltbld', 07:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:28 INFO - 'MOZ_NO_REMOTE': '1', 07:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:28 INFO - 'NO_EM_RESTART': '1', 07:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:28 INFO - 'PWD': '/builds/slave/test', 07:31:28 INFO - 'SHELL': '/bin/bash', 07:31:28 INFO - 'SHLVL': '1', 07:31:28 INFO - 'TERM': 'linux', 07:31:28 INFO - 'TMOUT': '86400', 07:31:28 INFO - 'USER': 'cltbld', 07:31:28 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:28 INFO - '_': '/tools/buildbot/bin/python'} 07:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:29 INFO - Downloading/unpacking blobuploader==1.2.4 07:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - Downloading blobuploader-1.2.4.tar.gz 07:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:31:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:31:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:31 INFO - Downloading docopt-0.6.1.tar.gz 07:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:31:31 INFO - Installing collected packages: blobuploader, requests, docopt 07:31:31 INFO - Running setup.py install for blobuploader 07:31:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:31:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:31:31 INFO - Running setup.py install for requests 07:31:32 INFO - Running setup.py install for docopt 07:31:32 INFO - Successfully installed blobuploader requests docopt 07:31:32 INFO - Cleaning up... 07:31:32 INFO - Return code: 0 07:31:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:31:32 INFO - Using env: {'DISPLAY': ':0', 07:31:32 INFO - 'HOME': '/home/cltbld', 07:31:32 INFO - 'LANG': 'en_US.UTF-8', 07:31:32 INFO - 'LANGUAGE': 'en_US:en', 07:31:32 INFO - 'LOGNAME': 'cltbld', 07:31:32 INFO - 'MAIL': '/var/mail/cltbld', 07:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:32 INFO - 'MOZ_NO_REMOTE': '1', 07:31:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:32 INFO - 'NO_EM_RESTART': '1', 07:31:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:32 INFO - 'PWD': '/builds/slave/test', 07:31:32 INFO - 'SHELL': '/bin/bash', 07:31:32 INFO - 'SHLVL': '1', 07:31:32 INFO - 'TERM': 'linux', 07:31:32 INFO - 'TMOUT': '86400', 07:31:32 INFO - 'USER': 'cltbld', 07:31:32 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:32 INFO - '_': '/tools/buildbot/bin/python'} 07:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:32 INFO - Running setup.py (path:/tmp/pip-Af_96z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:32 INFO - Running setup.py (path:/tmp/pip-Wzcw4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:32 INFO - Running setup.py (path:/tmp/pip-YEUG_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:32 INFO - Running setup.py (path:/tmp/pip-lKyuUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:33 INFO - Running setup.py (path:/tmp/pip-Yhdvbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:33 INFO - Running setup.py (path:/tmp/pip-hc0EQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:33 INFO - Running setup.py (path:/tmp/pip-UFbVrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:33 INFO - Running setup.py (path:/tmp/pip-QOlBrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:33 INFO - Running setup.py (path:/tmp/pip-1BQZbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:33 INFO - Running setup.py (path:/tmp/pip-e4vksv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:33 INFO - Running setup.py (path:/tmp/pip-6Q8KAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:33 INFO - Running setup.py (path:/tmp/pip-KPvmJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:33 INFO - Running setup.py (path:/tmp/pip-OSLrXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:33 INFO - Running setup.py (path:/tmp/pip-_sA8k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:33 INFO - Running setup.py (path:/tmp/pip-ACm2g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:33 INFO - Running setup.py (path:/tmp/pip-iY2R1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:33 INFO - Running setup.py (path:/tmp/pip-MKcdug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:31:33 INFO - Running setup.py install for manifestparser 07:31:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for mozcrash 07:31:34 INFO - Running setup.py install for mozdebug 07:31:34 INFO - Running setup.py install for mozdevice 07:31:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for mozfile 07:31:34 INFO - Running setup.py install for mozhttpd 07:31:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for mozinfo 07:31:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for mozInstall 07:31:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for mozleak 07:31:34 INFO - Running setup.py install for mozlog 07:31:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:31:34 INFO - Running setup.py install for moznetwork 07:31:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Running setup.py install for mozprocess 07:31:35 INFO - Running setup.py install for mozprofile 07:31:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Running setup.py install for mozrunner 07:31:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Running setup.py install for mozscreenshot 07:31:35 INFO - Running setup.py install for moztest 07:31:35 INFO - Running setup.py install for mozversion 07:31:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:31:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:31:35 INFO - Cleaning up... 07:31:35 INFO - Return code: 0 07:31:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:31:35 INFO - Using env: {'DISPLAY': ':0', 07:31:35 INFO - 'HOME': '/home/cltbld', 07:31:35 INFO - 'LANG': 'en_US.UTF-8', 07:31:35 INFO - 'LANGUAGE': 'en_US:en', 07:31:35 INFO - 'LOGNAME': 'cltbld', 07:31:35 INFO - 'MAIL': '/var/mail/cltbld', 07:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:35 INFO - 'MOZ_NO_REMOTE': '1', 07:31:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:35 INFO - 'NO_EM_RESTART': '1', 07:31:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:35 INFO - 'PWD': '/builds/slave/test', 07:31:35 INFO - 'SHELL': '/bin/bash', 07:31:35 INFO - 'SHLVL': '1', 07:31:35 INFO - 'TERM': 'linux', 07:31:35 INFO - 'TMOUT': '86400', 07:31:35 INFO - 'USER': 'cltbld', 07:31:35 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:35 INFO - '_': '/tools/buildbot/bin/python'} 07:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:31:35 INFO - Running setup.py (path:/tmp/pip-A2dBVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:31:36 INFO - Running setup.py (path:/tmp/pip-S3mc08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:31:36 INFO - Running setup.py (path:/tmp/pip-YpYQgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:31:36 INFO - Running setup.py (path:/tmp/pip-IIkNdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:31:36 INFO - Running setup.py (path:/tmp/pip-pVn106-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:31:36 INFO - Running setup.py (path:/tmp/pip-Tgz3RN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:31:36 INFO - Running setup.py (path:/tmp/pip-g3uo_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:31:36 INFO - Running setup.py (path:/tmp/pip-HttCX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:31:36 INFO - Running setup.py (path:/tmp/pip-FjTGGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:31:36 INFO - Running setup.py (path:/tmp/pip-BkVweM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:31:36 INFO - Running setup.py (path:/tmp/pip-HDSJZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:31:36 INFO - Running setup.py (path:/tmp/pip-VdE_E7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:31:36 INFO - Running setup.py (path:/tmp/pip-QS2axT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:31:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:31:36 INFO - Running setup.py (path:/tmp/pip-TBoFJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:37 INFO - Running setup.py (path:/tmp/pip-2f_I2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:31:37 INFO - Running setup.py (path:/tmp/pip-AdcFu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:31:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:31:37 INFO - Running setup.py (path:/tmp/pip-7Jk9KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:31:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:39 INFO - Downloading blessings-1.6.tar.gz 07:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:31:39 INFO - Installing collected packages: blessings 07:31:39 INFO - Running setup.py install for blessings 07:31:39 INFO - Successfully installed blessings 07:31:39 INFO - Cleaning up... 07:31:39 INFO - Return code: 0 07:31:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:31:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:39 INFO - Reading from file tmpfile_stdout 07:31:39 INFO - Current package versions: 07:31:39 INFO - argparse == 1.2.1 07:31:39 INFO - blessings == 1.6 07:31:39 INFO - blobuploader == 1.2.4 07:31:39 INFO - docopt == 0.6.1 07:31:39 INFO - functools32 == 3.2.3-2 07:31:39 INFO - jsonschema == 2.5.1 07:31:39 INFO - manifestparser == 1.1 07:31:39 INFO - mozInstall == 1.12 07:31:39 INFO - mozcrash == 1.0 07:31:39 INFO - mozdebug == 0.1 07:31:39 INFO - mozdevice == 0.49 07:31:39 INFO - mozfile == 1.2 07:31:39 INFO - mozhttpd == 0.7 07:31:39 INFO - mozinfo == 0.9 07:31:39 INFO - mozleak == 0.1 07:31:39 INFO - mozlog == 3.4 07:31:39 INFO - moznetwork == 0.27 07:31:39 INFO - mozprocess == 0.23 07:31:39 INFO - mozprofile == 0.28 07:31:39 INFO - mozrunner == 6.13 07:31:39 INFO - mozscreenshot == 0.1 07:31:39 INFO - mozsystemmonitor == 0.3 07:31:39 INFO - moztest == 0.7 07:31:39 INFO - mozversion == 1.4 07:31:39 INFO - psutil == 3.1.1 07:31:39 INFO - requests == 1.2.3 07:31:39 INFO - wsgiref == 0.1.2 07:31:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:31:39 INFO - Using env: {'DISPLAY': ':0', 07:31:39 INFO - 'HOME': '/home/cltbld', 07:31:39 INFO - 'LANG': 'en_US.UTF-8', 07:31:39 INFO - 'LANGUAGE': 'en_US:en', 07:31:39 INFO - 'LOGNAME': 'cltbld', 07:31:39 INFO - 'MAIL': '/var/mail/cltbld', 07:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:39 INFO - 'MOZ_NO_REMOTE': '1', 07:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:39 INFO - 'NO_EM_RESTART': '1', 07:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:39 INFO - 'PWD': '/builds/slave/test', 07:31:39 INFO - 'SHELL': '/bin/bash', 07:31:39 INFO - 'SHLVL': '1', 07:31:39 INFO - 'TERM': 'linux', 07:31:39 INFO - 'TMOUT': '86400', 07:31:39 INFO - 'USER': 'cltbld', 07:31:39 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:39 INFO - '_': '/tools/buildbot/bin/python'} 07:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:31:39 INFO - Cleaning up... 07:31:39 INFO - Return code: 0 07:31:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2155630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:31:39 INFO - Using env: {'DISPLAY': ':0', 07:31:39 INFO - 'HOME': '/home/cltbld', 07:31:39 INFO - 'LANG': 'en_US.UTF-8', 07:31:39 INFO - 'LANGUAGE': 'en_US:en', 07:31:39 INFO - 'LOGNAME': 'cltbld', 07:31:39 INFO - 'MAIL': '/var/mail/cltbld', 07:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:39 INFO - 'MOZ_NO_REMOTE': '1', 07:31:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:39 INFO - 'NO_EM_RESTART': '1', 07:31:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:39 INFO - 'PWD': '/builds/slave/test', 07:31:39 INFO - 'SHELL': '/bin/bash', 07:31:39 INFO - 'SHLVL': '1', 07:31:39 INFO - 'TERM': 'linux', 07:31:39 INFO - 'TMOUT': '86400', 07:31:39 INFO - 'USER': 'cltbld', 07:31:39 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:39 INFO - '_': '/tools/buildbot/bin/python'} 07:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:31:40 INFO - Cleaning up... 07:31:40 INFO - Return code: 0 07:31:40 INFO - Running post-action listener: _resource_record_post_action 07:31:40 INFO - Running post-action listener: _start_resource_monitoring 07:31:40 INFO - Starting resource monitoring. 07:31:40 INFO - [mozharness: 2016-12-25 15:31:40.200531Z] Finished create-virtualenv step (success) 07:31:40 INFO - [mozharness: 2016-12-25 15:31:40.201122Z] Running install step. 07:31:40 INFO - Running pre-action listener: _resource_record_pre_action 07:31:40 INFO - Running main action method: install 07:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:31:40 INFO - Reading from file tmpfile_stdout 07:31:40 INFO - Detecting whether we're running mozinstall >=1.0... 07:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:31:40 INFO - Reading from file tmpfile_stdout 07:31:40 INFO - Output received: 07:31:40 INFO - Usage: mozinstall [options] installer 07:31:40 INFO - Options: 07:31:40 INFO - -h, --help show this help message and exit 07:31:40 INFO - -d DEST, --destination=DEST 07:31:40 INFO - Directory to install application into. [default: 07:31:40 INFO - "/builds/slave/test"] 07:31:40 INFO - --app=APP Application being installed. [default: firefox] 07:31:40 INFO - mkdir: /builds/slave/test/build/application 07:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:31:56 INFO - Reading from file tmpfile_stdout 07:31:56 INFO - Output received: 07:31:56 INFO - /builds/slave/test/build/application/firefox/firefox 07:31:56 INFO - Running post-action listener: _resource_record_post_action 07:31:56 INFO - [mozharness: 2016-12-25 15:31:56.576561Z] Finished install step (success) 07:31:56 INFO - [mozharness: 2016-12-25 15:31:56.576887Z] Running run-tests step. 07:31:56 INFO - Running pre-action listener: _resource_record_pre_action 07:31:56 INFO - Running main action method: run_tests 07:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:31:56 INFO - Python 2.7.3 07:31:56 INFO - Return code: 0 07:31:56 INFO - grabbing minidump binary from tooltool 07:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21de850>, '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 0x21536d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2153b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:31:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:31:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:31:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:31:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:31:56 INFO - Return code: 0 07:31:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:31:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:31:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:31:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:31:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:31:56 INFO - Using env: {'DISPLAY': ':0', 07:31:56 INFO - 'HOME': '/home/cltbld', 07:31:56 INFO - 'LANG': 'en_US.UTF-8', 07:31:56 INFO - 'LANGUAGE': 'en_US:en', 07:31:56 INFO - 'LOGNAME': 'cltbld', 07:31:56 INFO - 'MAIL': '/var/mail/cltbld', 07:31:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:31:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:56 INFO - 'MOZ_NO_REMOTE': '1', 07:31:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:31:56 INFO - 'NO_EM_RESTART': '1', 07:31:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:56 INFO - 'PWD': '/builds/slave/test', 07:31:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:31:56 INFO - 'SHELL': '/bin/bash', 07:31:56 INFO - 'SHLVL': '1', 07:31:56 INFO - 'TERM': 'linux', 07:31:56 INFO - 'TMOUT': '86400', 07:31:56 INFO - 'USER': 'cltbld', 07:31:56 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335', 07:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:56 INFO - '_': '/tools/buildbot/bin/python'} 07:31:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:31:56 INFO - mozversion application_buildid: 20161225064336 07:31:56 INFO - mozversion application_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 07:31:56 INFO - mozversion application_display_name: Nightly 07:31:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:31:56 INFO - mozversion application_name: Firefox 07:31:56 INFO - mozversion application_remotingname: firefox 07:31:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:31:56 INFO - mozversion application_vendor: Mozilla 07:31:56 INFO - mozversion application_version: 53.0a1 07:31:56 INFO - mozversion platform_buildid: 20161225064336 07:31:56 INFO - mozversion platform_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 07:31:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:31:56 INFO - mozversion platform_version: 53.0a1 07:31:56 INFO - using testdate: 1482679916 07:31:56 INFO - actual date: 1482679916 07:31:56 INFO - starting webserver on 'localhost:47222' 07:31:56 INFO - SUITE-START | Running 2 tests 07:31:56 INFO - TEST-START | dromaeo_css 07:31:56 INFO - operating with platform_type : linux_ 07:31:56 INFO - Initialising browser for dromaeo_css test... 07:31:56 INFO - TEST-INFO | started process 10733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHzpqYs/profile http://localhost:47222/getInfo.html) 07:31:57 INFO - PROCESS | 10733 | 1482679917383 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:32:01 INFO - PROCESS | 10733 | __metrics Screen width/height:1600/1200 07:32:01 INFO - PROCESS | 10733 | colorDepth:24 07:32:01 INFO - PROCESS | 10733 | Browser inner width/height: 1024/697 07:32:01 INFO - PROCESS | 10733 | __metrics 07:32:02 INFO - PROCESS | 10733 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:02 INFO - PROCESS | 10733 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:03 INFO - PROCESS | 10733 | console.error: PushService: 07:32:03 INFO - PROCESS | 10733 | stateChangeProcessEnqueue: Error transitioning state 07:32:03 INFO - PROCESS | 10733 | UnknownError 07:32:04 INFO - TEST-INFO | 10733: exit 0 07:32:04 INFO - Browser initialized. 07:32:04 INFO - Running cycle 1/1 for dromaeo_css test... 07:32:04 INFO - TEST-INFO | started process 10813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHzpqYs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:16 INFO - PROCESS | 10813 | RSS: Main: 150564864 07:32:16 INFO - PROCESS | 10813 | 07:35:22 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47222/tests/dromaeo/cssquery-ext.html) 07:35:23 INFO - PROCESS | 10813 | RSS: Main: 248393728 07:35:23 INFO - PROCESS | 10813 | 07:37:05 INFO - PROCESS | 10813 | ************************* 07:37:05 INFO - PROCESS | 10813 | A coding exception was thrown and uncaught in a Task. 07:37:05 INFO - PROCESS | 10813 | 07:37:05 INFO - PROCESS | 10813 | Full message: ReferenceError: fetch is not defined 07:37:05 INFO - PROCESS | 10813 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:37:05 INFO - PROCESS | 10813 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:37:05 INFO - PROCESS | 10813 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:37:05 INFO - PROCESS | 10813 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:37:05 INFO - PROCESS | 10813 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:37:05 INFO - PROCESS | 10813 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:37:05 INFO - PROCESS | 10813 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:37:05 INFO - PROCESS | 10813 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:37:05 INFO - PROCESS | 10813 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:37:05 INFO - PROCESS | 10813 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:37:05 INFO - PROCESS | 10813 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:37:05 INFO - PROCESS | 10813 | 07:37:05 INFO - PROCESS | 10813 | ************************* 07:38:25 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-ext.html (next: http://localhost:47222/tests/dromaeo/cssquery-jquery.html) 07:38:26 INFO - PROCESS | 10813 | RSS: Main: 239255552 07:38:26 INFO - PROCESS | 10813 | 07:41:28 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47222/tests/dromaeo/cssquery-mootools.html) 07:41:29 INFO - PROCESS | 10813 | RSS: Main: 223416320 07:41:29 INFO - PROCESS | 10813 | 07:44:31 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47222/tests/dromaeo/cssquery-prototype.html) 07:44:32 INFO - PROCESS | 10813 | RSS: Main: 182218752 07:44:32 INFO - PROCESS | 10813 | 07:47:35 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47222/tests/dromaeo/cssquery-yui.html) 07:47:36 INFO - PROCESS | 10813 | RSS: Main: 181751808 07:47:36 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | Cycle 1(1): loaded http://localhost:47222/tests/dromaeo/cssquery-yui.html (next: http://localhost:47222/tests/dromaeo/cssquery-dojo.html) 07:50:39 INFO - PROCESS | 10813 | RSS: Main: 275820544 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | __start_tp_report 07:50:39 INFO - PROCESS | 10813 | _x_x_mozilla_page_load 07:50:39 INFO - PROCESS | 10813 | _x_x_mozilla_page_load_details 07:50:39 INFO - PROCESS | 10813 | |i|pagename|runs| 07:50:39 INFO - PROCESS | 10813 | |0;dojo.html;2557.7698007179356;2572.2026171886705;2587.068655284098;2598.441335112949;2603.3882037721132;4724.622030237581;4751.691140075893;4767.189577771778;4788.521147885205;4796.232602783551;4818.542238487338;4820.493848145938;4845.1763200255955;4878.48775878533;4935.679180853242;4389.539098394671;4414.7351158930405;4446.911061778763;4476.574725401082;4502.887427814315;4532.68271221013;4536;4552.68131230814;4554.453465584118;4560.863174104782;4292.0772034012525;4437.112577484516;4470.664700147502;4492.865214043584;4501.909961800762;4280.6361459275995;4350.521442641307;4354.194474022316;4418.911621767579;4443.42235509385;4389.604935555815;4406.581374769392;4434.512203657595;4460.4647442306805;4500.077484115748;4765.046990601893;4798.5441383068555;4820.975895120537;4825.7587120644175;4872.123017856784;4962.6526143169995;5033.320501732256;5075.390953085616;5155.304033955405;5165.354330708661;5130.050940575968;5200.76596553156;5210.609204309654;5216.452272511369;5249.553787928031;4866.975665121652;4867.36724225852;4870.537298956593;4873.049755297754;4919.950800492021;5190.688558686491;5313.760880760297;5315.946840531546;5323.441038690959;5369.194620806832;5016.372953380827;5016.698998060128;5024.447310795809;5061.6962982221185;5107.693538387709;5030.647854650139;5062.848114556569;5212.9739351303;5214.973925130426;5225.869353266123;5042.344495215599;5102.668326558836;5114.488551144856;5144.228365745168;5241.31862857826;5161.690298582022;5165.096108181053;5179.1195496764885;5180.48195180479;5185.040767458032;4269.188854117708;4376.6498680105515;4388.407564978751;4406.5593440655675;4419.668524860648;3725.1618385863508;3738.8691395801015;3751.0622344413896;3766;3826.1773718650065;4427.313766366218;4441.267190913463;4442.6890117691455;4446.421965144576;4479.843205487792;5221.190715439113;5228.790848366023;5229.267902493654;5231.973840130775;5280.735963201824;4349.391085248068;4367.650587952956;4373.081652852873;4395.428594282723;4401.163778882003;4626.051659409813;4639.930401043919;4647.37260469839;4679.9532004680195;4690.343351930733;4802.591779698765;4803.78382972774;4804.327394164819;4808.3027960946165;4857.028594281229;5063.392392912874;5107.770150343318;5114.156164233009;5169.457206993212;5178.585713143015;4703;4748.762561871962;4750.643701722315;4770.856874293777;4816.4220293564995;5048.268001139741;5055;5056.898862022813;5062.468440813662;5109.029284435946;5082.161443361952;5109.233614957638;5145.794168233228;5156.948430515797;5162.174052151638;4978.751062446936;5031.84904452867;5066.670666406671;5066.923996139987;5154.768035438489;4986.900261994812;5017.498250174954;5092.618053645917;5098.9490105098475;5132.409772876018;4870.220764677634;4909;4909.214525675875;4941.6293777966075;4988.575971042502;5041.521055499722;5070.163423035306;5085.7457127144235;5087.796488140433;5091.567216786467;4960.826371077139;4996.650234483552;4997.075541024922;4999.275105109811;5038.874028149179 07:50:39 INFO - PROCESS | 10813 | |1;ext.html;507.0037376555069;531.9122344813104;539.6141758642569;545.1767830575831;552.8617845538615;31691;32415;32449;32455;38446.615533844655;37695.81152094243;41191.38212926809;41216;41239.587604123946;41245.793771031174;7562.886556701654;7729;7737.535747855147;7739.380849532052;7742.961285193566;7639.694412223532;7644.61776911155;7745;7745.573993430359;7832.334251588622;9804.117629413351;10044.347117437379;10051.095401413872;10367.377957322547;10376.53305601246;10156.593736250541;10164.796704065951;10207.693769186937;10247.436390998491;10268.127209187229;9963;10019.947905469966;10025.799484010351;10035.79928401428;10036.29745917786;37836.43245351322;38094;38176.23647527062;38202.235955281016;38203;3541.929161416783;3657.7073834093208;3664.3587372210022;3700.4079347304573;3708.592054873962;28202.576961345596;28610.570841437395;28784.856075719697;28893;28972.27569310763;3305.735541156702;3328.3176948725454;3344.4147274227157;3387.1362802485455;3512.139525816166;239142;239182;239238;239397;240505;53405.732971334895;53650.19524707132;53722;53729.1940620891;53767.731161344724;56373.43626563764;56471;56485;56490.435095648514;56513;11406.714832129097;11550.94224528872;11554.59558915434;11570.305781653127;11573.826392604118;9291.581878815334;9348.392354496937;9365.484898330657;9369.297302702325;9369.765755856022;15313.310901009258;15724;16590.419335323204;16939.237734301987;17675.29298828058;6036.486898613667;6263.498920086382;6285.402886725754;6377.617342959437;6393.2328120625825;782.338923609549;792.6116203060459;805.7703554486943;815.8898548695969;829.3613917283658;25112.497750045262;26297.868510657325;26317.88580646422;26396;26591.468170636865;891.6210610490516;945.4043952310138;947.327397547735;956.5073986896754;966.3863446711389;12776.361181940865;13316.134451260637;13798;13902;13955.441782328797;318950;319048;320671;321487;321627;50092.49907500878;50098.49901500938;50225.74887125541;50227;50349.74825125851;1176.9588064417703;1205.1563905266348;1214.7631211913592;1221.9816702749465;1226.3255209634665;25305.493890121725;26687.866560667848;27022.459550808482;27416.862915685295;27639.170824875288;42398.788006059774;42863.57136428721;42877;43002.35496467493;43020.35469467898;280980;281391;282351;282516;283233;36510.24560456324;36893.44659830051;37124.62875371212;37269.813650931574;37286.813565931996;34756.47865282073;39461.40807887827;41878.371824423266;42338.788306058275;47646.5235347656;34035;35504.46743298801;36874.26251474902;37188.25623487569;37264.627353726115;35631.643683562834;35846.28307433784;35872.64127358693;36218.27563448769;36317;32826.67173328236;33554.664453356134;33581.83209083939;35878.103047424025;36078.098047547974;27795.44409111847;28836.42327153487;30415.239619009702;30544.236394089436;30720.692793071783;23231.186908458123;23709.770043195065;24102.27693169208;24269.271921842374;24271.271861844172 07:50:39 INFO - PROCESS | 10813 | |2;jquery.html;364.71187761668267;372.3353813443004;374.8013552817007;389.0468352536287;398.97406668566543;15500.612484687888;16262.918685406572;16788.24452899621;16932.746008809878;16938.745918811197;15891.125988070651;15981.200939953014;16195.91902040491;16310.553416987457;16315.368463153678;4793.089313030522;4985.451600323961;5007.098722229997;5019.447860735316;5029.748512574375;3554.982225088871;3717.832697528604;4840.830570930018;4934.087193869145;5571.133670220481;5770.624909380897;5897.3218079920925;5903.026000709878;5908.9409105909035;5912.7930522431725;5719.942800572025;5843.240378750777;5860.443257890493;5887.646741195542;5908.763649454017;5189.351331083614;5190.740462976836;5197.610179236534;5198.662086964373;5202.869928251786;18915.432537023913;19562;19633.705494417594;19640.312589059315;19689.409317720492;2008.2770202727006;2137.5190582119053;2158.298552970275;2172.1528603844513;2292.083166733303;8329.500229986159;8682.913170868274;8748.650053997833;8949.865752013726;9058.048904865025;1631.779709739182;1703.3612395351743;1730.4635562975393;1745.6770497457883;1770.4849394137298;64012.679936600485;65934.67032664854;67287;67554.32445675578;67636.6618166906;10103.19174466041;10216.540255688511;10223.897761022294;10293.176545876311;10338.638147664946;10852.24034317606;13845.930770346084;13860.653483662989;13868.375923083486;13872;6385.648789316632;6407.263164736115;6646.169228846395;6667.466602671871;6673.632950187689;6154.969225153845;6192.907106393408;6321.841953951188;6323.525735569759;6327.303996560375;3606.549181352331;3662.249238906018;3704.073981504624;3739.1960728443514;3751.81240937952;1767.7260024696193;1823.3527097880326;1827.9360222392152;1842.4564753397513;1876.07134468439;791.2562191539934;819.1644522587047;824.4352618456377;829.5644786487022;847.3602430165186;15301.846981530265;15508.122969257685;16107.355705771872;16454.83545164557;17101.48695539111;755.8979537762442;789.9328557072713;802.2859654907137;807.3742849291729;823.2302796884932;8877.556122193993;8878.86681699775;9014.143656352637;9689.27330450219;10569.577216911392;267529;270366;270613;360005;373761;77789.61105194438;77941.61029194818;82009.58995205106;82687;83710.58144709359;925.8750068740768;928.8095940332215;955.5604421965835;961.624966263158;968.6584081047658;14749.377568467246;14927.626809329853;15615.21923903823;15634.06195628266;15783.526494205193;34908.30183396267;35203.82398088096;35723.28553428969;36072.81963590165;36220;44383.33424998563;47966.32538793776;48369.27446088382;48735.75632121817;48783.75608122079;7665.731699390494;7686;7809.375249980101;7887.132415434183;7924.920750792418;9329.86005209936;11572.189946703988;11680.357580332824;11695.064394848561;11822.704432389259;8921.197092261727;9024.954875225581;9655.710328690151;10046.598136074768;10684.786304274026;4990.700557966534;7438.735414979575;10388.117010053928;10461.738456538647;10789;9343.112404321579;9511.619535218513;9538.666146684833;9545.522723863918;10038.899611004088;7709.4603377763015;7878.960605197167;8049.074356448849;8079.798005049921;8256.83486330282;9096.090390960851;9285.67500137519;9319.86020209711;9390.154886060287;9654.951725241328 07:50:39 INFO - PROCESS | 10813 | |3;mootools.html;1307.3071272225718;1314.9276789776561;1318.222248873165;1333.3733145421647;1333.4599487207684;6649.368310010554;7002.194747604027;7012.26371231021;7015.508914375996;7018.017477553147;6430.421262086423;6670.866582668344;6935.930640693591;7117.786466406016;7177.964110179455;2134.349023547821;2167.2847960173103;2168.642174041281;2169.902354394056;2170.5333353329033;2060.8248298894537;2151.6880052392394;2159.719236499253;2163.167180635451;2164.101897712447;1848.5009047557219;1896.3741965151537;2072.1089931329598;2130.968035479469;2131.9786802131935;1997.6104659591385;2018.8788672679539;2024.4129202531396;2026.878387296767;2027.3208475160839;2142.7642959274467;2169.967450488244;2170.1536400803698;2172.858764180339;2172.989135054315;7395.186529481753;7412.295831895961;7433.85132297351;7437;7457.291557302047;4352.020795321059;4489.034857505652;4499.122671079141;4501.977490112561;4508.391367165456;2882.3802882380387;2971.732544071044;3054.7708921830954;3055.740262077726;3058.4189004089153;1115.4980258883454;1158.345534772859;1201.9362863865454;1206.39077265981;1233.2970206982025;9731.610735570568;9744.90255097454;9813.264005199639;9821.214302855755;9824.361416507976;3979.820908059144;4072.429859819628;4183.288840897055;4194.2869712149;4198.244316023084;8423.536705481258;8428.199321064489;8428.704995325133;8431.367647426467;8435.367347448966;4440.777961101997;4444.688871779009;4445.5776701213335;4445.59989600931;4453.042595841845;3968.146848427602;4020.4974378202724;4021.939670904939;4025.4163146344204;4052.250333688277;3524;3532.057368447395;3619.6561326673923;3660.743747937672;3677.5770786359913;1189.3636904256182;1196.7187710887933;1203.9819402708965;1216.7627312674122;1227.4415141110774;573.0945106731355;669.6752075243503;678.0541145102554;689.1110467496973;802.6668932393109;4868.14807408702;4873.537013983666;5045.520675535819;5128.692278463304;5185.48145185486;868.7306934850217;870.1255238485281;872.9738107856774;874.208840998897;874.2787200559563;3292.86828526861;3464.3071385722956;3481.2341284917784;3494.545709057806;3608.6210947850623;8557.358198135165;8745.038045814956;8756.299496040301;8817.911820881709;8818.735437936872;8746.12538746133;8757.386982911135;8758.912410875937;8764.03595604483;8775.91224087751;6712.630805305755;6797.1843378794865;6809;6914.896276555956;6939.722411103499;4886.902261954812;4916.950830491649;4920.1389756791705;4961.578265847299;5013.473585273494;7770.45606807518;7945.0863150738405;7994.640241189044;8033.879491807745;8083.312918402002;7820.257075577811;8010.639521221442;8045.195480451908;8157.510549367057;8211.302039326723;6422;6524.608523488606;6540.869182616226;6587.604743715392;6598.637074960731;6980.092587963735;7073.752418665322;7075.292470752884;7075.292470752884;7075.752348667772;6101.664408457577;6174.289956655042;6181.320054794059;6183.227096612924;6347.460465860268;6266.905996410149;6288.905666415099;6317.6209427434505;6363.6499992499;6423.0686550450855;5416.24172615842;5471.8084867029465;5472.726363681721;5479.53423958968;5494.697791621499;4186.916261674811;4207.915841683211;4243.830246790094;4330.437043184366;4415.8012889419415;4150.5641907600475;4188.7905604719035;4253.149370126049;4317.9352309716005;4329.328954012133 07:50:39 INFO - PROCESS | 10813 | |4;prototype.html;350.36583783352137;354.34976817539814;356.40836211888256;361.35498135827544;362.54500601744815;4880.973796246323;4900.676817259342;4918.065567542165;4929.704217746928;4931.679440836343;4834.782434790434;4858.805647774094;4890.606177239488;4896.975515122419;4901.950980490194;3766.322062028834;3871.9612803871883;3896.2597106549806;3910.960890391088;3911.9022024449473;3930.8820735377985;3944.152007318426;3954.387070004154;3958.4260282259056;3970.344893092636;3913.6869050476034;3915.7454765440298;3922.5881282465357;3931.331673615478;3946.782926939017;3765.3034188675183;3831.8084095795098;3861.3049651062797;3913.5890731473355;3949.782761948092;3546.840392182358;3548.946765798515;3552.182997910495;3556.3065202285566;3558;4762.190427627277;4768.928466073012;4774.761261936889;4781.426228852525;4781.952180478185;4294.227039132955;4467.419235499397;4479.664025198123;4516.345129956138;4542.954570454286;5119.436861945183;5125.692458452505;5132.5893928486;5146.356705411824;5150.27896094547;3890.3386424307937;3904.414337849317;3910.807203802839;3917.6865850732156;3919.9020024499614;5239.685618862843;5338.733063346817;5389.622726409153;5413.377461591928;5419.512243898068;3954.683625309968;3967.4725230786357;3977.284088864032;3980.402939559089;3987.142764305688;4039.3738970459626;4041.7574945503366;4043.5147782266317;4044.1102957349335;4050.210209009215;4447.977760111179;4448.5773851484055;4462.062966777014;4471.754053527087;4486.147631949919;4969.130402179604;5047.192449208109;5074.543291103819;5118.436971933084;5124.69251844883;1352.5401363536446;1358.3344161360862;1370.397025308861;1372.9771320366192;1377.5316392426623;891.777055736066;952.6046690623314;956.2350119904188;958.4105774948496;965.5365424596233;4244.511881133647;4323.935140972888;4377.949292169857;4453.548499912594;4497.887552811206;5274;5287.286216360819;5296.92054619181;5315.787368505294;5331.893362132736;4466.754328511963;4467.967074980876;4471.441069866267;4471.821127154943;4490.012197316585;2620.751028652275;2698.704621781556;2699;2710.837349759021;2717.3478365192213;4720.598749106296;4751.809927602857;4794.424669039738;4798.49615790337;4809.78355973982;5058.966328503163;5180.119379705459;5211.947880521222;5264;5308.256844041837;4649.907001860011;4688.156131896223;4701.425022617462;4733.621310295168;4734.7632618369635;5079.87300317495;5088.796448142033;5136.666116702402;5142.323238884216;5199.0227475981665;4921.630877684116;5035.471275516018;5064.468230835857;5069.1635880079375;5105.821296254613;4695.812167513262;4729.527047295243;4731.834385796481;4764.666473346832;4778.331033655221;3742.8689995850013;3773.6603705665525;3783.6405541473514;3807.238552289498;3833.2367111129192;5089.854782673869;5090.567301779391;5135.151345515686;5148.845534633967;5163.432022477599;4672.415948006499;4875.8049678012485;4952.8490370016225;4975.407869481731;5012.220661665202;4956.925646115384;4960.77676504551;4961.503849615154;4968.652194346361;4974.626902982219;4914.36113305268;4921.8523444296725;4924.212126059813;4934.238459886227;4946.208606623067;4832.565069143794;4984.925226121539;4985.675931064469;5120;5154.01570395393;5036.748162591783;5047.318611987482;5051.267566202807;5056.216286475602;5061.266116413026 07:50:39 INFO - PROCESS | 10813 | |5;yui.html;669.4811521071169;675.598362246091;679.7790718016644;680.9163958287637;684.5105749389186;14302.427902883885;14930.626734331647;15061.322240499176;15800.051996880195;15830.84169158308;13659.453621855138;14928.925355373234;14976.475823346185;15007.474738384159;15026.924865375686;661.9801405957805;800.3677095094882;824.0194168938943;826.2728798657173;827.6565225431469;677.1839932880866;796.928276455119;811.4644334739073;823.54704912298;828.4490813608973;851.8637018077109;853.1127627267634;859.4843094143495;859.5702148925537;861.3582880753847;860.8321377331423;861.2421069458867;862.0345213361013;862.2455351567347;862.7066797288952;512.4030504462297;523.5131327865084;529.3383270911361;529.875479262373;607.3380015782818;13568.93215533916;14239.288035598178;14264.643383915381;14279.78580321296;14288.428462861473;12603.747925041447;12757.23456592598;12897.806532902014;13108.86891131086;13122.34388280582;11833.112516561207;11920.403979800974;11922.284662920165;12304.753904921852;12319.5072197112;4075;4084.305668036441;4090.570490098556;4275.230458517465;4286.142771445723;69045.65477172582;69235;69287;69314.30685693129;69368.65315673439;9931.801363972825;9984.500774961223;10052.497375131214;10057.899421005843;10237.846432303522;15752.291146898187;15780.526584202493;15962.201889905458;17566.912165439346;18983.71524427135;6145.4161854623735;6188.938110618927;6204.565680402418;6212.074564757504;6345.0482427636225;3175;3211.4219440500933;3229.6285927118684;3250.057483329855;3283.7701360904507;2808.5225511663066;2856.0289501569564;2879.337752316979;2896.579995900625;2900.274931267183;485.2599785327333;489.06832484117814;489.3760455419298;494.04402481198997;525.0706249937589;554.6256277013;586.4428792647002;591.4174538079988;592.8577141486012;594.7918228620035;7876.724279753315;8162.959185204036;8178.345732341399;8269.485854566323;8366.958165209135;523.7381309345327;529.8225094593355;539.2154415325691;540.6782964136332;543.4646872830259;3674.614165512636;3691.6492933171307;3757.1546402059867;3781.8487260509823;3843.3082045232127;66420;68656;69107.65446172738;70878.64560677267;71197.2880271201;46583.068338633035;47817.52182478131;55684.16473752897;56139.438605613424;57061.42938570561;53873;53982.19026714681;54918.450815491335;55044.724776375064;55138.72430637821;7166.641667916479;7199.964000180175;7360.96319518399;7518.360939320219;7561.130469996021;35967.64032359643;36579.26841463209;36610.63389366072;37684.43473347957;37897.81051094727;37280.44079338866;40634.79682601568;41803.37294940639;41992;42601;11153.609623663133;11370.17373314383;11372.20394572372;11552;11665;12410.689732756753;12635.1787133836;12808.781349556908;12861.678458038625;13156;11355.432228388712;11469;11605.129615278718;11750.58872939443;11847.289162650268;11195.496202671062;11557.761508872069;11558.479868405773;11926.642200733992;12322.260664360167;11925.76148477043;12016.399180041139;12068.215565988183;12318.260904345769;12783.680407989503;8680.522571258389;8915.153060459246;8940.597673104856;9072.637094516409;9108.635654574007;8258.298044666271;8536.74389768308;8655.394122411371;8680.565971701264;8885.733427997171 07:50:39 INFO - PROCESS | 10813 | __end_tp_report 07:50:39 INFO - PROCESS | 10813 | __start_cc_report 07:50:39 INFO - PROCESS | 10813 | _x_x_mozilla_cycle_collect,1101 07:50:39 INFO - PROCESS | 10813 | __end_cc_report 07:50:39 INFO - PROCESS | 10813 | __startTimestamp1482681039469__endTimestamp 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | ------- Summary: start ------- 07:50:39 INFO - PROCESS | 10813 | Number of tests: 6 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#0] dojo.html Cycles:165 Average:4760.61 Median:4873.05 stddev:518.70 (10.6%) stddev-sans-first:490.66 07:50:39 INFO - PROCESS | 10813 | Values: 2557.8 2572.2 2587.1 2598.4 2603.4 4724.6 4751.7 4767.2 4788.5 4796.2 4818.5 4820.5 4845.2 4878.5 4935.7 4389.5 4414.7 4446.9 4476.6 4502.9 4532.7 4536.0 4552.7 4554.5 4560.9 4292.1 4437.1 4470.7 4492.9 4501.9 4280.6 4350.5 4354.2 4418.9 4443.4 4389.6 4406.6 4434.5 4460.5 4500.1 4765.0 4798.5 4821.0 4825.8 4872.1 4962.7 5033.3 5075.4 5155.3 5165.4 5130.1 5200.8 5210.6 5216.5 5249.6 4867.0 4867.4 4870.5 4873.0 4920.0 5190.7 5313.8 5315.9 5323.4 5369.2 5016.4 5016.7 5024.4 5061.7 5107.7 5030.6 5062.8 5213.0 5215.0 5225.9 5042.3 5102.7 5114.5 5144.2 5241.3 5161.7 5165.1 5179.1 5180.5 5185.0 4269.2 4376.6 4388.4 4406.6 4419.7 3725.2 3738.9 3751.1 3766.0 3826.2 4427.3 4441.3 4442.7 4446.4 4479.8 5221.2 5228.8 5229.3 5232.0 5280.7 4349.4 4367.7 4373.1 4395.4 4401.2 4626.1 4639.9 4647.4 4680.0 4690.3 4802.6 4803.8 4804.3 4808.3 4857.0 5063.4 5107.8 5114.2 5169.5 5178.6 4703.0 4748.8 4750.6 4770.9 4816.4 5048.3 5055.0 5056.9 5062.5 5109.0 5082.2 5109.2 5145.8 5156.9 5162.2 4978.8 5031.8 5066.7 5066.9 5154.8 4986.9 5017.5 5092.6 5098.9 5132.4 4870.2 4909.0 4909.2 4941.6 4988.6 5041.5 5070.2 5085.7 5087.8 5091.6 4960.8 4996.7 4997.1 4999.3 5038.9 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#1] ext.html Cycles:180 Average:44184.80 Median:35859.46 stddev:73926.48 (206.2%) stddev-sans-first:74061.12 07:50:39 INFO - PROCESS | 10813 | Values: 507.0 531.9 539.6 545.2 552.9 31691.0 32415.0 32449.0 32455.0 38446.6 37695.8 41191.4 41216.0 41239.6 41245.8 7562.9 7729.0 7737.5 7739.4 7743.0 7639.7 7644.6 7745.0 7745.6 7832.3 9804.1 10044.3 10051.1 10367.4 10376.5 10156.6 10164.8 10207.7 10247.4 10268.1 9963.0 10019.9 10025.8 10035.8 10036.3 37836.4 38094.0 38176.2 38202.2 38203.0 3541.9 3657.7 3664.4 3700.4 3708.6 28202.6 28610.6 28784.9 28893.0 28972.3 3305.7 3328.3 3344.4 3387.1 3512.1 239142.0 239182.0 239238.0 239397.0 240505.0 53405.7 53650.2 53722.0 53729.2 53767.7 56373.4 56471.0 56485.0 56490.4 56513.0 11406.7 11550.9 11554.6 11570.3 11573.8 9291.6 9348.4 9365.5 9369.3 9369.8 15313.3 15724.0 16590.4 16939.2 17675.3 6036.5 6263.5 6285.4 6377.6 6393.2 782.3 792.6 805.8 815.9 829.4 25112.5 26297.9 26317.9 26396.0 26591.5 891.6 945.4 947.3 956.5 966.4 12776.4 13316.1 13798.0 13902.0 13955.4 318950.0 319048.0 320671.0 321487.0 321627.0 50092.5 50098.5 50225.7 50227.0 50349.7 1177.0 1205.2 1214.8 1222.0 1226.3 25305.5 26687.9 27022.5 27416.9 27639.2 42398.8 42863.6 42877.0 43002.4 43020.4 280980.0 281391.0 282351.0 282516.0 283233.0 36510.2 36893.4 37124.6 37269.8 37286.8 34756.5 39461.4 41878.4 42338.8 47646.5 34035.0 35504.5 36874.3 37188.3 37264.6 35631.6 35846.3 35872.6 36218.3 36317.0 32826.7 33554.7 33581.8 35878.1 36078.1 27795.4 28836.4 30415.2 30544.2 30720.7 23231.2 23709.8 24102.3 24269.3 24271.3 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#2] jquery.html Cycles:180 Average:21673.45 Median:4959.77 stddev:52252.91 (1053.5%) stddev-sans-first:52375.00 07:50:39 INFO - PROCESS | 10813 | Values: 364.7 372.3 374.8 389.0 399.0 15500.6 16262.9 16788.2 16932.7 16938.7 15891.1 15981.2 16195.9 16310.6 16315.4 4793.1 4985.5 5007.1 5019.4 5029.7 3555.0 3717.8 4840.8 4934.1 5571.1 5770.6 5897.3 5903.0 5908.9 5912.8 5719.9 5843.2 5860.4 5887.6 5908.8 5189.4 5190.7 5197.6 5198.7 5202.9 18915.4 19562.0 19633.7 19640.3 19689.4 2008.3 2137.5 2158.3 2172.2 2292.1 8329.5 8682.9 8748.7 8949.9 9058.0 1631.8 1703.4 1730.5 1745.7 1770.5 64012.7 65934.7 67287.0 67554.3 67636.7 10103.2 10216.5 10223.9 10293.2 10338.6 10852.2 13845.9 13860.7 13868.4 13872.0 6385.6 6407.3 6646.2 6667.5 6673.6 6155.0 6192.9 6321.8 6323.5 6327.3 3606.5 3662.2 3704.1 3739.2 3751.8 1767.7 1823.4 1827.9 1842.5 1876.1 791.3 819.2 824.4 829.6 847.4 15301.8 15508.1 16107.4 16454.8 17101.5 755.9 789.9 802.3 807.4 823.2 8877.6 8878.9 9014.1 9689.3 10569.6 267529.0 270366.0 270613.0 360005.0 373761.0 77789.6 77941.6 82009.6 82687.0 83710.6 925.9 928.8 955.6 961.6 968.7 14749.4 14927.6 15615.2 15634.1 15783.5 34908.3 35203.8 35723.3 36072.8 36220.0 44383.3 47966.3 48369.3 48735.8 48783.8 7665.7 7686.0 7809.4 7887.1 7924.9 9329.9 11572.2 11680.4 11695.1 11822.7 8921.2 9025.0 9655.7 10046.6 10684.8 4990.7 7438.7 10388.1 10461.7 10789.0 9343.1 9511.6 9538.7 9545.5 10038.9 7709.5 7879.0 8049.1 8079.8 8256.8 9096.1 9285.7 9319.9 9390.2 9655.0 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#3] mootools.html Cycles:180 Average:4799.30 Median:4987.53 stddev:2579.01 (51.7%) stddev-sans-first:2572.89 07:50:39 INFO - PROCESS | 10813 | Values: 1307.3 1314.9 1318.2 1333.4 1333.5 6649.4 7002.2 7012.3 7015.5 7018.0 6430.4 6670.9 6935.9 7117.8 7178.0 2134.3 2167.3 2168.6 2169.9 2170.5 2060.8 2151.7 2159.7 2163.2 2164.1 1848.5 1896.4 2072.1 2131.0 2132.0 1997.6 2018.9 2024.4 2026.9 2027.3 2142.8 2170.0 2170.2 2172.9 2173.0 7395.2 7412.3 7433.9 7437.0 7457.3 4352.0 4489.0 4499.1 4502.0 4508.4 2882.4 2971.7 3054.8 3055.7 3058.4 1115.5 1158.3 1201.9 1206.4 1233.3 9731.6 9744.9 9813.3 9821.2 9824.4 3979.8 4072.4 4183.3 4194.3 4198.2 8423.5 8428.2 8428.7 8431.4 8435.4 4440.8 4444.7 4445.6 4445.6 4453.0 3968.1 4020.5 4021.9 4025.4 4052.3 3524.0 3532.1 3619.7 3660.7 3677.6 1189.4 1196.7 1204.0 1216.8 1227.4 573.1 669.7 678.1 689.1 802.7 4868.1 4873.5 5045.5 5128.7 5185.5 868.7 870.1 873.0 874.2 874.3 3292.9 3464.3 3481.2 3494.5 3608.6 8557.4 8745.0 8756.3 8817.9 8818.7 8746.1 8757.4 8758.9 8764.0 8775.9 6712.6 6797.2 6809.0 6914.9 6939.7 4886.9 4917.0 4920.1 4961.6 5013.5 7770.5 7945.1 7994.6 8033.9 8083.3 7820.3 8010.6 8045.2 8157.5 8211.3 6422.0 6524.6 6540.9 6587.6 6598.6 6980.1 7073.8 7075.3 7075.3 7075.8 6101.7 6174.3 6181.3 6183.2 6347.5 6266.9 6288.9 6317.6 6363.6 6423.1 5416.2 5471.8 5472.7 5479.5 5494.7 4186.9 4207.9 4243.8 4330.4 4415.8 4150.6 4188.8 4253.1 4317.9 4329.3 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#4] prototype.html Cycles:180 Average:4250.33 Median:4757.00 stddev:1179.35 (24.8%) stddev-sans-first:1145.75 07:50:39 INFO - PROCESS | 10813 | Values: 350.4 354.3 356.4 361.4 362.5 4881.0 4900.7 4918.1 4929.7 4931.7 4834.8 4858.8 4890.6 4897.0 4902.0 3766.3 3872.0 3896.3 3911.0 3911.9 3930.9 3944.2 3954.4 3958.4 3970.3 3913.7 3915.7 3922.6 3931.3 3946.8 3765.3 3831.8 3861.3 3913.6 3949.8 3546.8 3548.9 3552.2 3556.3 3558.0 4762.2 4768.9 4774.8 4781.4 4782.0 4294.2 4467.4 4479.7 4516.3 4543.0 5119.4 5125.7 5132.6 5146.4 5150.3 3890.3 3904.4 3910.8 3917.7 3919.9 5239.7 5338.7 5389.6 5413.4 5419.5 3954.7 3967.5 3977.3 3980.4 3987.1 4039.4 4041.8 4043.5 4044.1 4050.2 4448.0 4448.6 4462.1 4471.8 4486.1 4969.1 5047.2 5074.5 5118.4 5124.7 1352.5 1358.3 1370.4 1373.0 1377.5 891.8 952.6 956.2 958.4 965.5 4244.5 4323.9 4377.9 4453.5 4497.9 5274.0 5287.3 5296.9 5315.8 5331.9 4466.8 4468.0 4471.4 4471.8 4490.0 2620.8 2698.7 2699.0 2710.8 2717.3 4720.6 4751.8 4794.4 4798.5 4809.8 5059.0 5180.1 5211.9 5264.0 5308.3 4649.9 4688.2 4701.4 4733.6 4734.8 5079.9 5088.8 5136.7 5142.3 5199.0 4921.6 5035.5 5064.5 5069.2 5105.8 4695.8 4729.5 4731.8 4764.7 4778.3 3742.9 3773.7 3783.6 3807.2 3833.2 5089.9 5090.6 5135.2 5148.8 5163.4 4672.4 4875.8 4952.8 4975.4 5012.2 4956.9 4960.8 4961.5 4968.7 4974.6 4914.4 4921.9 4924.2 4934.2 4946.2 4832.6 4984.9 4985.7 5120.0 5154.0 5036.7 5047.3 5051.3 5056.2 5061.3 07:50:39 INFO - PROCESS | 10813 | 07:50:39 INFO - PROCESS | 10813 | [#5] yui.html Cycles:180 Average:14960.80 Median:487.16 stddev:18892.04 (3878.0%) stddev-sans-first:18914.55 07:50:39 INFO - PROCESS | 10813 | Values: 669.5 675.6 679.8 680.9 684.5 14302.4 14930.6 15061.3 15800.1 15830.8 13659.5 14928.9 14976.5 15007.5 15026.9 662.0 800.4 824.0 826.3 827.7 677.2 796.9 811.5 823.5 828.4 851.9 853.1 859.5 859.6 861.4 860.8 861.2 862.0 862.2 862.7 512.4 523.5 529.3 529.9 607.3 13568.9 14239.3 14264.6 14279.8 14288.4 12603.7 12757.2 12897.8 13108.9 13122.3 11833.1 11920.4 11922.3 12304.8 12319.5 4075.0 4084.3 4090.6 4275.2 4286.1 69045.7 69235.0 69287.0 69314.3 69368.7 9931.8 9984.5 10052.5 10057.9 10237.8 15752.3 15780.5 15962.2 17566.9 18983.7 6145.4 6188.9 6204.6 6212.1 6345.0 3175.0 3211.4 3229.6 3250.1 3283.8 2808.5 2856.0 2879.3 2896.6 2900.3 485.3 489.1 489.4 494.0 525.1 554.6 586.4 591.4 592.9 594.8 7876.7 8163.0 8178.3 8269.5 8367.0 523.7 529.8 539.2 540.7 543.5 3674.6 3691.6 3757.2 3781.8 3843.3 66420.0 68656.0 69107.7 70878.6 71197.3 46583.1 47817.5 55684.2 56139.4 57061.4 53873.0 53982.2 54918.5 55044.7 55138.7 7166.6 7200.0 7361.0 7518.4 7561.1 35967.6 36579.3 36610.6 37684.4 37897.8 37280.4 40634.8 41803.4 41992.0 42601.0 11153.6 11370.2 11372.2 11552.0 11665.0 12410.7 12635.2 12808.8 12861.7 13156.0 11355.4 11469.0 11605.1 11750.6 11847.3 11195.5 11557.8 11558.5 11926.6 12322.3 11925.8 12016.4 12068.2 12318.3 12783.7 8680.5 8915.2 8940.6 9072.6 9108.6 8258.3 8536.7 8655.4 8680.6 8885.7 07:50:39 INFO - PROCESS | 10813 | -------- Summary: end -------- 07:50:39 INFO - PROCESS | 10813 | 07:50:40 INFO - TEST-INFO | 10813: exit 0 07:50:40 INFO - TEST-OK | dromaeo_css | took 1123504ms 07:50:40 INFO - TEST-START | kraken 07:50:40 INFO - operating with platform_type : linux_ 07:50:40 INFO - Initialising browser for kraken test... 07:50:40 INFO - TEST-INFO | started process 11226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGWNkHz/profile http://localhost:47222/getInfo.html) 07:50:40 INFO - PROCESS | 11226 | 1482681040838 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:50:44 INFO - PROCESS | 11226 | __metrics Screen width/height:1600/1200 07:50:44 INFO - PROCESS | 11226 | colorDepth:24 07:50:44 INFO - PROCESS | 11226 | Browser inner width/height: 1024/697 07:50:44 INFO - PROCESS | 11226 | __metrics 07:50:45 INFO - PROCESS | 11226 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:50:45 INFO - PROCESS | 11226 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:50:46 INFO - PROCESS | 11226 | console.error: PushService: 07:50:46 INFO - PROCESS | 11226 | stateChangeProcessEnqueue: Error transitioning state 07:50:46 INFO - PROCESS | 11226 | UnknownError 07:50:47 INFO - TEST-INFO | 11226: exit 0 07:50:47 INFO - Browser initialized. 07:50:47 INFO - Running cycle 1/1 for kraken test... 07:50:47 INFO - TEST-INFO | started process 11305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGWNkHz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:50:59 INFO - PROCESS | 11305 | RSS: Main: 155103232 07:50:59 INFO - PROCESS | 11305 | 07:51:01 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47222/tests/kraken/driver.html?testName=audio-beat-detection) 07:51:02 INFO - PROCESS | 11305 | RSS: Main: 208330752 07:51:02 INFO - PROCESS | 11305 | 07:51:05 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47222/tests/kraken/driver.html?testName=audio-dft) 07:51:06 INFO - PROCESS | 11305 | RSS: Main: 188678144 07:51:06 INFO - PROCESS | 11305 | 07:51:09 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47222/tests/kraken/driver.html?testName=audio-fft) 07:51:09 INFO - PROCESS | 11305 | RSS: Main: 271106048 07:51:09 INFO - PROCESS | 11305 | 07:51:11 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47222/tests/kraken/driver.html?testName=audio-oscillator) 07:51:12 INFO - PROCESS | 11305 | RSS: Main: 182931456 07:51:12 INFO - PROCESS | 11305 | 07:51:14 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:51:15 INFO - PROCESS | 11305 | RSS: Main: 185880576 07:51:15 INFO - PROCESS | 11305 | 07:51:18 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47222/tests/kraken/driver.html?testName=imaging-darkroom) 07:51:19 INFO - PROCESS | 11305 | RSS: Main: 225140736 07:51:19 INFO - PROCESS | 11305 | 07:51:22 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47222/tests/kraken/driver.html?testName=imaging-desaturate) 07:51:23 INFO - PROCESS | 11305 | RSS: Main: 219197440 07:51:23 INFO - PROCESS | 11305 | 07:51:26 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47222/tests/kraken/driver.html?testName=json-parse-financial) 07:51:26 INFO - PROCESS | 11305 | RSS: Main: 220917760 07:51:26 INFO - PROCESS | 11305 | 07:51:28 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:51:28 INFO - PROCESS | 11305 | RSS: Main: 184307712 07:51:28 INFO - PROCESS | 11305 | 07:51:30 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:51:31 INFO - PROCESS | 11305 | RSS: Main: 187809792 07:51:31 INFO - PROCESS | 11305 | 07:51:33 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:51:34 INFO - PROCESS | 11305 | RSS: Main: 196526080 07:51:34 INFO - PROCESS | 11305 | 07:51:37 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:51:37 INFO - PROCESS | 11305 | RSS: Main: 205680640 07:51:37 INFO - PROCESS | 11305 | 07:51:40 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:51:41 INFO - PROCESS | 11305 | RSS: Main: 201834496 07:51:41 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | Cycle 1(1): loaded http://localhost:47222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47222/tests/kraken/driver.html?testName=ai-astar) 07:51:43 INFO - PROCESS | 11305 | RSS: Main: 253685760 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | __start_tp_report 07:51:43 INFO - PROCESS | 11305 | _x_x_mozilla_page_load 07:51:43 INFO - PROCESS | 11305 | _x_x_mozilla_page_load_details 07:51:43 INFO - PROCESS | 11305 | |i|pagename|runs| 07:51:43 INFO - PROCESS | 11305 | |0;ai-astar;112;116;114;115;114;116;114;114;113;114 07:51:43 INFO - PROCESS | 11305 | |1;audio-beat-detection;157;124;129;163;129;133;125;127;156;125 07:51:43 INFO - PROCESS | 11305 | |2;audio-dft;162;162;161;161;162;162;163;163;161;161 07:51:43 INFO - PROCESS | 11305 | |3;audio-fft;76;76;76;122;79;77;78;76;103;75 07:51:43 INFO - PROCESS | 11305 | |4;audio-oscillator;85;85;110;84;84;85;107;85;88;116 07:51:43 INFO - PROCESS | 11305 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;118;117 07:51:43 INFO - PROCESS | 11305 | |6;imaging-darkroom;150;149;149;149;149;149;149;149;149;148 07:51:43 INFO - PROCESS | 11305 | |7;imaging-desaturate;96;96;96;96;95;96;95;95;95;95 07:51:43 INFO - PROCESS | 11305 | |8;json-parse-financial;62;61;61;62;63;63;68;64;62;63 07:51:43 INFO - PROCESS | 11305 | |9;json-stringify-tinderbox;67;68;67;67;68;67;66;67;67;67 07:51:43 INFO - PROCESS | 11305 | |10;stanford-crypto-aes;85;84;84;84;84;84;84;85;84;84 07:51:43 INFO - PROCESS | 11305 | |11;stanford-crypto-ccm;135;138;144;134;131;131;145;131;184;156 07:51:43 INFO - PROCESS | 11305 | |12;stanford-crypto-pbkdf2;181;182;186;181;183;185;186;180;183;182 07:51:43 INFO - PROCESS | 11305 | |13;stanford-crypto-sha256-iterative;69;73;69;68;68;69;69;72;69;70 07:51:43 INFO - PROCESS | 11305 | __end_tp_report 07:51:43 INFO - PROCESS | 11305 | __start_cc_report 07:51:43 INFO - PROCESS | 11305 | _x_x_mozilla_cycle_collect,1837 07:51:43 INFO - PROCESS | 11305 | __end_cc_report 07:51:43 INFO - PROCESS | 11305 | __startTimestamp1482681103593__endTimestamp 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | ------- Summary: start ------- 07:51:43 INFO - PROCESS | 11305 | Number of tests: 14 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 07:51:43 INFO - PROCESS | 11305 | Values: 112.0 116.0 114.0 115.0 114.0 116.0 114.0 114.0 113.0 114.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:131.00 stddev:15.41 (11.8%) stddev-sans-first:14.51 07:51:43 INFO - PROCESS | 11305 | Values: 157.0 124.0 129.0 163.0 129.0 133.0 125.0 127.0 156.0 125.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 07:51:43 INFO - PROCESS | 11305 | Values: 162.0 162.0 161.0 161.0 162.0 162.0 163.0 163.0 161.0 161.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#3] audio-fft Cycles:10 Average:83.80 Median:76.00 stddev:15.82 (20.8%) stddev-sans-first:16.52 07:51:43 INFO - PROCESS | 11305 | Values: 76.0 76.0 76.0 122.0 79.0 77.0 78.0 76.0 103.0 75.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:85.00 stddev:12.72 (15.0%) stddev-sans-first:13.17 07:51:43 INFO - PROCESS | 11305 | Values: 85.0 85.0 110.0 84.0 84.0 85.0 107.0 85.0 88.0 116.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 07:51:43 INFO - PROCESS | 11305 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 117.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 07:51:43 INFO - PROCESS | 11305 | Values: 150.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 148.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 07:51:43 INFO - PROCESS | 11305 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:2.02 (3.2%) stddev-sans-first:2.12 07:51:43 INFO - PROCESS | 11305 | Values: 62.0 61.0 61.0 62.0 63.0 63.0 68.0 64.0 62.0 63.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 07:51:43 INFO - PROCESS | 11305 | Values: 67.0 68.0 67.0 67.0 68.0 67.0 66.0 67.0 67.0 67.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.33 07:51:43 INFO - PROCESS | 11305 | Values: 85.0 84.0 84.0 84.0 84.0 84.0 84.0 85.0 84.0 84.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:141.00 stddev:16.52 (11.7%) stddev-sans-first:17.28 07:51:43 INFO - PROCESS | 11305 | Values: 135.0 138.0 144.0 134.0 131.0 131.0 145.0 131.0 184.0 156.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:183.00 stddev:2.13 (1.2%) stddev-sans-first:2.15 07:51:43 INFO - PROCESS | 11305 | Values: 181.0 182.0 186.0 181.0 183.0 185.0 186.0 180.0 183.0 182.0 07:51:43 INFO - PROCESS | 11305 | 07:51:43 INFO - PROCESS | 11305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:69.00 stddev:1.65 (2.4%) stddev-sans-first:1.73 07:51:43 INFO - PROCESS | 11305 | Values: 69.0 73.0 69.0 68.0 68.0 69.0 69.0 72.0 69.0 70.0 07:51:43 INFO - PROCESS | 11305 | -------- Summary: end -------- 07:51:43 INFO - PROCESS | 11305 | 07:51:44 INFO - TEST-INFO | 11305: exit 0 07:51:44 INFO - TEST-OK | kraken | took 64452ms 07:51:44 INFO - SUITE-END | took 1187s 07:51:45 INFO - Completed test suite (00:19:48) 07:51:45 INFO - javascript benchmark 07:51:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2557.7698007179356, 2572.2026171886705, 2587.068655284098, 2598.441335112949, 2603.3882037721132, 4724.622030237581, 4751.691140075893, 4767.189577771778, 4788.521147885205, 4796.232602783551, 4818.542238487338, 4820.493848145938, 4845.1763200255955, 4878.48775878533, 4935.679180853242, 4389.539098394671, 4414.7351158930405, 4446.911061778763, 4476.574725401082, 4502.887427814315, 4532.68271221013, 4536.0, 4552.68131230814, 4554.453465584118, 4560.863174104782, 4292.0772034012525, 4437.112577484516, 4470.664700147502, 4492.865214043584, 4501.909961800762, 4280.6361459275995, 4350.521442641307, 4354.194474022316, 4418.911621767579, 4443.42235509385, 4389.604935555815, 4406.581374769392, 4434.512203657595, 4460.4647442306805, 4500.077484115748, 4765.046990601893, 4798.5441383068555, 4820.975895120537, 4825.7587120644175, 4872.123017856784, 4962.6526143169995, 5033.320501732256, 5075.390953085616, 5155.304033955405, 5165.354330708661, 5130.050940575968, 5200.76596553156, 5210.609204309654, 5216.452272511369, 5249.553787928031, 4866.975665121652, 4867.36724225852, 4870.537298956593, 4873.049755297754, 4919.950800492021, 5190.688558686491, 5313.760880760297, 5315.946840531546, 5323.441038690959, 5369.194620806832, 5016.372953380827, 5016.698998060128, 5024.447310795809, 5061.6962982221185, 5107.693538387709, 5030.647854650139, 5062.848114556569, 5212.9739351303, 5214.973925130426, 5225.869353266123, 5042.344495215599, 5102.668326558836, 5114.488551144856, 5144.228365745168, 5241.31862857826, 5161.690298582022, 5165.096108181053, 5179.1195496764885, 5180.48195180479, 5185.040767458032, 4269.188854117708, 4376.6498680105515, 4388.407564978751, 4406.5593440655675, 4419.668524860648, 3725.1618385863508, 3738.8691395801015, 3751.0622344413896, 3766.0, 3826.1773718650065, 4427.313766366218, 4441.267190913463, 4442.6890117691455, 4446.421965144576, 4479.843205487792, 5221.190715439113, 5228.790848366023, 5229.267902493654, 5231.973840130775, 5280.735963201824, 4349.391085248068, 4367.650587952956, 4373.081652852873, 4395.428594282723, 4401.163778882003, 4626.051659409813, 4639.930401043919, 4647.37260469839, 4679.9532004680195, 4690.343351930733, 4802.591779698765, 4803.78382972774, 4804.327394164819, 4808.3027960946165, 4857.028594281229, 5063.392392912874, 5107.770150343318, 5114.156164233009, 5169.457206993212, 5178.585713143015, 4703.0, 4748.762561871962, 4750.643701722315, 4770.856874293777, 4816.4220293564995, 5048.268001139741, 5055.0, 5056.898862022813, 5062.468440813662, 5109.029284435946, 5082.161443361952, 5109.233614957638, 5145.794168233228, 5156.948430515797, 5162.174052151638, 4978.751062446936, 5031.84904452867, 5066.670666406671, 5066.923996139987, 5154.768035438489, 4986.900261994812, 5017.498250174954, 5092.618053645917, 5098.9490105098475, 5132.409772876018, 4870.220764677634, 4909.0, 4909.214525675875, 4941.6293777966075, 4988.575971042502, 5041.521055499722, 5070.163423035306, 5085.7457127144235, 5087.796488140433, 5091.567216786467, 4960.826371077139, 4996.650234483552, 4997.075541024922, 4999.275105109811, 5038.874028149179], "value": 4725.404739984662, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.0037376555069, 531.9122344813104, 539.6141758642569, 545.1767830575831, 552.8617845538615, 31691.0, 32415.0, 32449.0, 32455.0, 38446.615533844655, 37695.81152094243, 41191.38212926809, 41216.0, 41239.587604123946, 41245.793771031174, 7562.886556701654, 7729.0, 7737.535747855147, 7739.380849532052, 7742.961285193566, 7639.694412223532, 7644.61776911155, 7745.0, 7745.573993430359, 7832.334251588622, 9804.117629413351, 10044.347117437379, 10051.095401413872, 10367.377957322547, 10376.53305601246, 10156.593736250541, 10164.796704065951, 10207.693769186937, 10247.436390998491, 10268.127209187229, 9963.0, 10019.947905469966, 10025.799484010351, 10035.79928401428, 10036.29745917786, 37836.43245351322, 38094.0, 38176.23647527062, 38202.235955281016, 38203.0, 3541.929161416783, 3657.7073834093208, 3664.3587372210022, 3700.4079347304573, 3708.592054873962, 28202.576961345596, 28610.570841437395, 28784.856075719697, 28893.0, 28972.27569310763, 3305.735541156702, 3328.3176948725454, 3344.4147274227157, 3387.1362802485455, 3512.139525816166, 239142.0, 239182.0, 239238.0, 239397.0, 240505.0, 53405.732971334895, 53650.19524707132, 53722.0, 53729.1940620891, 53767.731161344724, 56373.43626563764, 56471.0, 56485.0, 56490.435095648514, 56513.0, 11406.714832129097, 11550.94224528872, 11554.59558915434, 11570.305781653127, 11573.826392604118, 9291.581878815334, 9348.392354496937, 9365.484898330657, 9369.297302702325, 9369.765755856022, 15313.310901009258, 15724.0, 16590.419335323204, 16939.237734301987, 17675.29298828058, 6036.486898613667, 6263.498920086382, 6285.402886725754, 6377.617342959437, 6393.2328120625825, 782.338923609549, 792.6116203060459, 805.7703554486943, 815.8898548695969, 829.3613917283658, 25112.497750045262, 26297.868510657325, 26317.88580646422, 26396.0, 26591.468170636865, 891.6210610490516, 945.4043952310138, 947.327397547735, 956.5073986896754, 966.3863446711389, 12776.361181940865, 13316.134451260637, 13798.0, 13902.0, 13955.441782328797, 318950.0, 319048.0, 320671.0, 321487.0, 321627.0, 50092.49907500878, 50098.49901500938, 50225.74887125541, 50227.0, 50349.74825125851, 1176.9588064417703, 1205.1563905266348, 1214.7631211913592, 1221.9816702749465, 1226.3255209634665, 25305.493890121725, 26687.866560667848, 27022.459550808482, 27416.862915685295, 27639.170824875288, 42398.788006059774, 42863.57136428721, 42877.0, 43002.35496467493, 43020.35469467898, 280980.0, 281391.0, 282351.0, 282516.0, 283233.0, 36510.24560456324, 36893.44659830051, 37124.62875371212, 37269.813650931574, 37286.813565931996, 34756.47865282073, 39461.40807887827, 41878.371824423266, 42338.788306058275, 47646.5235347656, 34035.0, 35504.46743298801, 36874.26251474902, 37188.25623487569, 37264.627353726115, 35631.643683562834, 35846.28307433784, 35872.64127358693, 36218.27563448769, 36317.0, 32826.67173328236, 33554.664453356134, 33581.83209083939, 35878.103047424025, 36078.098047547974, 27795.44409111847, 28836.42327153487, 30415.239619009702, 30544.236394089436, 30720.692793071783, 23231.186908458123, 23709.770043195065, 24102.27693169208, 24269.271921842374, 24271.271861844172], "value": 17337.0134437702, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.71187761668267, 372.3353813443004, 374.8013552817007, 389.0468352536287, 398.97406668566543, 15500.612484687888, 16262.918685406572, 16788.24452899621, 16932.746008809878, 16938.745918811197, 15891.125988070651, 15981.200939953014, 16195.91902040491, 16310.553416987457, 16315.368463153678, 4793.089313030522, 4985.451600323961, 5007.098722229997, 5019.447860735316, 5029.748512574375, 3554.982225088871, 3717.832697528604, 4840.830570930018, 4934.087193869145, 5571.133670220481, 5770.624909380897, 5897.3218079920925, 5903.026000709878, 5908.9409105909035, 5912.7930522431725, 5719.942800572025, 5843.240378750777, 5860.443257890493, 5887.646741195542, 5908.763649454017, 5189.351331083614, 5190.740462976836, 5197.610179236534, 5198.662086964373, 5202.869928251786, 18915.432537023913, 19562.0, 19633.705494417594, 19640.312589059315, 19689.409317720492, 2008.2770202727006, 2137.5190582119053, 2158.298552970275, 2172.1528603844513, 2292.083166733303, 8329.500229986159, 8682.913170868274, 8748.650053997833, 8949.865752013726, 9058.048904865025, 1631.779709739182, 1703.3612395351743, 1730.4635562975393, 1745.6770497457883, 1770.4849394137298, 64012.679936600485, 65934.67032664854, 67287.0, 67554.32445675578, 67636.6618166906, 10103.19174466041, 10216.540255688511, 10223.897761022294, 10293.176545876311, 10338.638147664946, 10852.24034317606, 13845.930770346084, 13860.653483662989, 13868.375923083486, 13872.0, 6385.648789316632, 6407.263164736115, 6646.169228846395, 6667.466602671871, 6673.632950187689, 6154.969225153845, 6192.907106393408, 6321.841953951188, 6323.525735569759, 6327.303996560375, 3606.549181352331, 3662.249238906018, 3704.073981504624, 3739.1960728443514, 3751.81240937952, 1767.7260024696193, 1823.3527097880326, 1827.9360222392152, 1842.4564753397513, 1876.07134468439, 791.2562191539934, 819.1644522587047, 824.4352618456377, 829.5644786487022, 847.3602430165186, 15301.846981530265, 15508.122969257685, 16107.355705771872, 16454.83545164557, 17101.48695539111, 755.8979537762442, 789.9328557072713, 802.2859654907137, 807.3742849291729, 823.2302796884932, 8877.556122193993, 8878.86681699775, 9014.143656352637, 9689.27330450219, 10569.577216911392, 267529.0, 270366.0, 270613.0, 360005.0, 373761.0, 77789.61105194438, 77941.61029194818, 82009.58995205106, 82687.0, 83710.58144709359, 925.8750068740768, 928.8095940332215, 955.5604421965835, 961.624966263158, 968.6584081047658, 14749.377568467246, 14927.626809329853, 15615.21923903823, 15634.06195628266, 15783.526494205193, 34908.30183396267, 35203.82398088096, 35723.28553428969, 36072.81963590165, 36220.0, 44383.33424998563, 47966.32538793776, 48369.27446088382, 48735.75632121817, 48783.75608122079, 7665.731699390494, 7686.0, 7809.375249980101, 7887.132415434183, 7924.920750792418, 9329.86005209936, 11572.189946703988, 11680.357580332824, 11695.064394848561, 11822.704432389259, 8921.197092261727, 9024.954875225581, 9655.710328690151, 10046.598136074768, 10684.786304274026, 4990.700557966534, 7438.735414979575, 10388.117010053928, 10461.738456538647, 10789.0, 9343.112404321579, 9511.619535218513, 9538.666146684833, 9545.522723863918, 10038.899611004088, 7709.4603377763015, 7878.960605197167, 8049.074356448849, 8079.798005049921, 8256.83486330282, 9096.090390960851, 9285.67500137519, 9319.86020209711, 9390.154886060287, 9654.951725241328], "value": 7903.53695150914, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.3071272225718, 1314.9276789776561, 1318.222248873165, 1333.3733145421647, 1333.4599487207684, 6649.368310010554, 7002.194747604027, 7012.26371231021, 7015.508914375996, 7018.017477553147, 6430.421262086423, 6670.866582668344, 6935.930640693591, 7117.786466406016, 7177.964110179455, 2134.349023547821, 2167.2847960173103, 2168.642174041281, 2169.902354394056, 2170.5333353329033, 2060.8248298894537, 2151.6880052392394, 2159.719236499253, 2163.167180635451, 2164.101897712447, 1848.5009047557219, 1896.3741965151537, 2072.1089931329598, 2130.968035479469, 2131.9786802131935, 1997.6104659591385, 2018.8788672679539, 2024.4129202531396, 2026.878387296767, 2027.3208475160839, 2142.7642959274467, 2169.967450488244, 2170.1536400803698, 2172.858764180339, 2172.989135054315, 7395.186529481753, 7412.295831895961, 7433.85132297351, 7437.0, 7457.291557302047, 4352.020795321059, 4489.034857505652, 4499.122671079141, 4501.977490112561, 4508.391367165456, 2882.3802882380387, 2971.732544071044, 3054.7708921830954, 3055.740262077726, 3058.4189004089153, 1115.4980258883454, 1158.345534772859, 1201.9362863865454, 1206.39077265981, 1233.2970206982025, 9731.610735570568, 9744.90255097454, 9813.264005199639, 9821.214302855755, 9824.361416507976, 3979.820908059144, 4072.429859819628, 4183.288840897055, 4194.2869712149, 4198.244316023084, 8423.536705481258, 8428.199321064489, 8428.704995325133, 8431.367647426467, 8435.367347448966, 4440.777961101997, 4444.688871779009, 4445.5776701213335, 4445.59989600931, 4453.042595841845, 3968.146848427602, 4020.4974378202724, 4021.939670904939, 4025.4163146344204, 4052.250333688277, 3524.0, 3532.057368447395, 3619.6561326673923, 3660.743747937672, 3677.5770786359913, 1189.3636904256182, 1196.7187710887933, 1203.9819402708965, 1216.7627312674122, 1227.4415141110774, 573.0945106731355, 669.6752075243503, 678.0541145102554, 689.1110467496973, 802.6668932393109, 4868.14807408702, 4873.537013983666, 5045.520675535819, 5128.692278463304, 5185.48145185486, 868.7306934850217, 870.1255238485281, 872.9738107856774, 874.208840998897, 874.2787200559563, 3292.86828526861, 3464.3071385722956, 3481.2341284917784, 3494.545709057806, 3608.6210947850623, 8557.358198135165, 8745.038045814956, 8756.299496040301, 8817.911820881709, 8818.735437936872, 8746.12538746133, 8757.386982911135, 8758.912410875937, 8764.03595604483, 8775.91224087751, 6712.630805305755, 6797.1843378794865, 6809.0, 6914.896276555956, 6939.722411103499, 4886.902261954812, 4916.950830491649, 4920.1389756791705, 4961.578265847299, 5013.473585273494, 7770.45606807518, 7945.0863150738405, 7994.640241189044, 8033.879491807745, 8083.312918402002, 7820.257075577811, 8010.639521221442, 8045.195480451908, 8157.510549367057, 8211.302039326723, 6422.0, 6524.608523488606, 6540.869182616226, 6587.604743715392, 6598.637074960731, 6980.092587963735, 7073.752418665322, 7075.292470752884, 7075.292470752884, 7075.752348667772, 6101.664408457577, 6174.289956655042, 6181.320054794059, 6183.227096612924, 6347.460465860268, 6266.905996410149, 6288.905666415099, 6317.6209427434505, 6363.6499992499, 6423.0686550450855, 5416.24172615842, 5471.8084867029465, 5472.726363681721, 5479.53423958968, 5494.697791621499, 4186.916261674811, 4207.915841683211, 4243.830246790094, 4330.437043184366, 4415.8012889419415, 4150.5641907600475, 4188.7905604719035, 4253.149370126049, 4317.9352309716005, 4329.328954012133], "value": 3929.1062682652205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.36583783352137, 354.34976817539814, 356.40836211888256, 361.35498135827544, 362.54500601744815, 4880.973796246323, 4900.676817259342, 4918.065567542165, 4929.704217746928, 4931.679440836343, 4834.782434790434, 4858.805647774094, 4890.606177239488, 4896.975515122419, 4901.950980490194, 3766.322062028834, 3871.9612803871883, 3896.2597106549806, 3910.960890391088, 3911.9022024449473, 3930.8820735377985, 3944.152007318426, 3954.387070004154, 3958.4260282259056, 3970.344893092636, 3913.6869050476034, 3915.7454765440298, 3922.5881282465357, 3931.331673615478, 3946.782926939017, 3765.3034188675183, 3831.8084095795098, 3861.3049651062797, 3913.5890731473355, 3949.782761948092, 3546.840392182358, 3548.946765798515, 3552.182997910495, 3556.3065202285566, 3558.0, 4762.190427627277, 4768.928466073012, 4774.761261936889, 4781.426228852525, 4781.952180478185, 4294.227039132955, 4467.419235499397, 4479.664025198123, 4516.345129956138, 4542.954570454286, 5119.436861945183, 5125.692458452505, 5132.5893928486, 5146.356705411824, 5150.27896094547, 3890.3386424307937, 3904.414337849317, 3910.807203802839, 3917.6865850732156, 3919.9020024499614, 5239.685618862843, 5338.733063346817, 5389.622726409153, 5413.377461591928, 5419.512243898068, 3954.683625309968, 3967.4725230786357, 3977.284088864032, 3980.402939559089, 3987.142764305688, 4039.3738970459626, 4041.7574945503366, 4043.5147782266317, 4044.1102957349335, 4050.210209009215, 4447.977760111179, 4448.5773851484055, 4462.062966777014, 4471.754053527087, 4486.147631949919, 4969.130402179604, 5047.192449208109, 5074.543291103819, 5118.436971933084, 5124.69251844883, 1352.5401363536446, 1358.3344161360862, 1370.397025308861, 1372.9771320366192, 1377.5316392426623, 891.777055736066, 952.6046690623314, 956.2350119904188, 958.4105774948496, 965.5365424596233, 4244.511881133647, 4323.935140972888, 4377.949292169857, 4453.548499912594, 4497.887552811206, 5274.0, 5287.286216360819, 5296.92054619181, 5315.787368505294, 5331.893362132736, 4466.754328511963, 4467.967074980876, 4471.441069866267, 4471.821127154943, 4490.012197316585, 2620.751028652275, 2698.704621781556, 2699.0, 2710.837349759021, 2717.3478365192213, 4720.598749106296, 4751.809927602857, 4794.424669039738, 4798.49615790337, 4809.78355973982, 5058.966328503163, 5180.119379705459, 5211.947880521222, 5264.0, 5308.256844041837, 4649.907001860011, 4688.156131896223, 4701.425022617462, 4733.621310295168, 4734.7632618369635, 5079.87300317495, 5088.796448142033, 5136.666116702402, 5142.323238884216, 5199.0227475981665, 4921.630877684116, 5035.471275516018, 5064.468230835857, 5069.1635880079375, 5105.821296254613, 4695.812167513262, 4729.527047295243, 4731.834385796481, 4764.666473346832, 4778.331033655221, 3742.8689995850013, 3773.6603705665525, 3783.6405541473514, 3807.238552289498, 3833.2367111129192, 5089.854782673869, 5090.567301779391, 5135.151345515686, 5148.845534633967, 5163.432022477599, 4672.415948006499, 4875.8049678012485, 4952.8490370016225, 4975.407869481731, 5012.220661665202, 4956.925646115384, 4960.77676504551, 4961.503849615154, 4968.652194346361, 4974.626902982219, 4914.36113305268, 4921.8523444296725, 4924.212126059813, 4934.238459886227, 4946.208606623067, 4832.565069143794, 4984.925226121539, 4985.675931064469, 5120.0, 5154.01570395393, 5036.748162591783, 5047.318611987482, 5051.267566202807, 5056.216286475602, 5061.266116413026], "value": 3893.693655372362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.4811521071169, 675.598362246091, 679.7790718016644, 680.9163958287637, 684.5105749389186, 14302.427902883885, 14930.626734331647, 15061.322240499176, 15800.051996880195, 15830.84169158308, 13659.453621855138, 14928.925355373234, 14976.475823346185, 15007.474738384159, 15026.924865375686, 661.9801405957805, 800.3677095094882, 824.0194168938943, 826.2728798657173, 827.6565225431469, 677.1839932880866, 796.928276455119, 811.4644334739073, 823.54704912298, 828.4490813608973, 851.8637018077109, 853.1127627267634, 859.4843094143495, 859.5702148925537, 861.3582880753847, 860.8321377331423, 861.2421069458867, 862.0345213361013, 862.2455351567347, 862.7066797288952, 512.4030504462297, 523.5131327865084, 529.3383270911361, 529.875479262373, 607.3380015782818, 13568.93215533916, 14239.288035598178, 14264.643383915381, 14279.78580321296, 14288.428462861473, 12603.747925041447, 12757.23456592598, 12897.806532902014, 13108.86891131086, 13122.34388280582, 11833.112516561207, 11920.403979800974, 11922.284662920165, 12304.753904921852, 12319.5072197112, 4075.0, 4084.305668036441, 4090.570490098556, 4275.230458517465, 4286.142771445723, 69045.65477172582, 69235.0, 69287.0, 69314.30685693129, 69368.65315673439, 9931.801363972825, 9984.500774961223, 10052.497375131214, 10057.899421005843, 10237.846432303522, 15752.291146898187, 15780.526584202493, 15962.201889905458, 17566.912165439346, 18983.71524427135, 6145.4161854623735, 6188.938110618927, 6204.565680402418, 6212.074564757504, 6345.0482427636225, 3175.0, 3211.4219440500933, 3229.6285927118684, 3250.057483329855, 3283.7701360904507, 2808.5225511663066, 2856.0289501569564, 2879.337752316979, 2896.579995900625, 2900.274931267183, 485.2599785327333, 489.06832484117814, 489.3760455419298, 494.04402481198997, 525.0706249937589, 554.6256277013, 586.4428792647002, 591.4174538079988, 592.8577141486012, 594.7918228620035, 7876.724279753315, 8162.959185204036, 8178.345732341399, 8269.485854566323, 8366.958165209135, 523.7381309345327, 529.8225094593355, 539.2154415325691, 540.6782964136332, 543.4646872830259, 3674.614165512636, 3691.6492933171307, 3757.1546402059867, 3781.8487260509823, 3843.3082045232127, 66420.0, 68656.0, 69107.65446172738, 70878.64560677267, 71197.2880271201, 46583.068338633035, 47817.52182478131, 55684.16473752897, 56139.438605613424, 57061.42938570561, 53873.0, 53982.19026714681, 54918.450815491335, 55044.724776375064, 55138.72430637821, 7166.641667916479, 7199.964000180175, 7360.96319518399, 7518.360939320219, 7561.130469996021, 35967.64032359643, 36579.26841463209, 36610.63389366072, 37684.43473347957, 37897.81051094727, 37280.44079338866, 40634.79682601568, 41803.37294940639, 41992.0, 42601.0, 11153.609623663133, 11370.17373314383, 11372.20394572372, 11552.0, 11665.0, 12410.689732756753, 12635.1787133836, 12808.781349556908, 12861.678458038625, 13156.0, 11355.432228388712, 11469.0, 11605.129615278718, 11750.58872939443, 11847.289162650268, 11195.496202671062, 11557.761508872069, 11558.479868405773, 11926.642200733992, 12322.260664360167, 11925.76148477043, 12016.399180041139, 12068.215565988183, 12318.260904345769, 12783.680407989503, 8680.522571258389, 8915.153060459246, 8940.597673104856, 9072.637094516409, 9108.635654574007, 8258.298044666271, 8536.74389768308, 8655.394122411371, 8680.565971701264, 8885.733427997171], "value": 6254.289062319316, "unit": "score"}], "name": "dromaeo_css", "value": 6290.515581112606, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 114.0, 115.0, 114.0, 116.0, 114.0, 114.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 129.0, 163.0, 129.0, 133.0, 125.0, 127.0, 156.0, 125.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 161.0, 162.0, 162.0, 163.0, 163.0, 161.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 122.0, 79.0, 77.0, 78.0, 76.0, 103.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 110.0, 84.0, 84.0, 85.0, 107.0, 85.0, 88.0, 116.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 62.0, 63.0, 63.0, 68.0, 64.0, 62.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 66.0, 67.0, 67.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 138.0, 144.0, 134.0, 131.0, 131.0, 145.0, 131.0, 184.0, 156.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 186.0, 181.0, 183.0, 185.0, 186.0, 180.0, 183.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 69.0, 68.0, 68.0, 69.0, 69.0, 72.0, 69.0, 70.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1560.6000000000001, "alertThreshold": 2.0}]} 07:51:45 INFO - Return code: 0 07:51:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:51:45 INFO - # TBPL SUCCESS # 07:51:45 INFO - Running post-action listener: _resource_record_post_action 07:51:45 INFO - [mozharness: 2016-12-25 15:51:45.410882Z] Finished run-tests step (success) 07:51:45 INFO - Running post-run listener: _resource_record_post_run 07:51:45 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4485120; Write bytes: 404541440; Read time: 4092; Write time: 1011420 07:51:45 INFO - TinderboxPrint: CPU usage
13.2% 07:51:45 INFO - TinderboxPrint: I/O read bytes / time
4,485,120 / 4,092 07:51:45 INFO - TinderboxPrint: I/O write bytes / time
404,541,440 / 1,011,420 07:51:45 INFO - TinderboxPrint: CPU idle
8,347.6 (86.8%) 07:51:45 INFO - TinderboxPrint: CPU user
1,215.6 (12.6%) 07:51:45 INFO - TinderboxPrint: Swap in / out
0 / 0 07:51:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 111190016; Read time: 0; Write time: 266544 07:51:45 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 4481024; Write bytes: 293351424; Read time: 4068; Write time: 744876 07:51:46 INFO - Running post-run listener: _upload_blobber_files 07:51:46 INFO - Blob upload gear active. 07:51:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:51:46 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 07:51:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:51:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:51:46 INFO - (blobuploader) - INFO - Open directory for files ... 07:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:51:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:47 INFO - (blobuploader) - INFO - Done attempting. 07:51:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:51:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:50 INFO - (blobuploader) - INFO - Done attempting. 07:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:51 INFO - (blobuploader) - INFO - Done attempting. 07:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:51:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:51:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:51:52 INFO - (blobuploader) - INFO - Done attempting. 07:51:52 INFO - (blobuploader) - INFO - Iteration through files over. 07:51:52 INFO - Return code: 0 07:51:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:51:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:51:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d78086fd64b32b7000e99f035c05b31836221392eac69eafb6f3e89e1d26cf6751328ee8e091a9ea5dd4054be80f3c7eec229cc6b8f4e941a1d4665564b14202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16adbcf04105ce2549ac845cf208dd6512fa026ce22869fcf898e905ae90e51bfb83da1602dbc6844630a1f77b4117fa566e54fd0a32a54d9c8c58838d2c4e32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f65e883709ecbdca0c5fdd6b87c3296e471dc0041cce56f4174c851a1f3b45335683bbcb6c5a7457c204585ac1d755c13ffc7df33cbc1ac247cf7f536b6f11ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e201fc93d0e137f2922bd2ef940bebeed889eed5513c5d7c8158492278ec8c13c669dcd31d2d33474656406acf43722102f0989ac6cdb60e6fb737f3e97a5619"} 07:51:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:51:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:51:52 INFO - Contents: 07:51:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d78086fd64b32b7000e99f035c05b31836221392eac69eafb6f3e89e1d26cf6751328ee8e091a9ea5dd4054be80f3c7eec229cc6b8f4e941a1d4665564b14202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16adbcf04105ce2549ac845cf208dd6512fa026ce22869fcf898e905ae90e51bfb83da1602dbc6844630a1f77b4117fa566e54fd0a32a54d9c8c58838d2c4e32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f65e883709ecbdca0c5fdd6b87c3296e471dc0041cce56f4174c851a1f3b45335683bbcb6c5a7457c204585ac1d755c13ffc7df33cbc1ac247cf7f536b6f11ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e201fc93d0e137f2922bd2ef940bebeed889eed5513c5d7c8158492278ec8c13c669dcd31d2d33474656406acf43722102f0989ac6cdb60e6fb737f3e97a5619"} 07:51:52 INFO - Running post-run listener: copy_logs_to_upload_dir 07:51:52 INFO - Copying logs to upload dir... 07:51:52 INFO - mkdir: /builds/slave/test/build/upload/logs 07:51:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.207205 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-12-25 07:51:52.308126) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 07:51:52.314007) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d78086fd64b32b7000e99f035c05b31836221392eac69eafb6f3e89e1d26cf6751328ee8e091a9ea5dd4054be80f3c7eec229cc6b8f4e941a1d4665564b14202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16adbcf04105ce2549ac845cf208dd6512fa026ce22869fcf898e905ae90e51bfb83da1602dbc6844630a1f77b4117fa566e54fd0a32a54d9c8c58838d2c4e32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f65e883709ecbdca0c5fdd6b87c3296e471dc0041cce56f4174c851a1f3b45335683bbcb6c5a7457c204585ac1d755c13ffc7df33cbc1ac247cf7f536b6f11ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e201fc93d0e137f2922bd2ef940bebeed889eed5513c5d7c8158492278ec8c13c669dcd31d2d33474656406acf43722102f0989ac6cdb60e6fb737f3e97a5619"} build_url:https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/UXtuYiDaQLCQvsF5q-8MwA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d78086fd64b32b7000e99f035c05b31836221392eac69eafb6f3e89e1d26cf6751328ee8e091a9ea5dd4054be80f3c7eec229cc6b8f4e941a1d4665564b14202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/16adbcf04105ce2549ac845cf208dd6512fa026ce22869fcf898e905ae90e51bfb83da1602dbc6844630a1f77b4117fa566e54fd0a32a54d9c8c58838d2c4e32", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f65e883709ecbdca0c5fdd6b87c3296e471dc0041cce56f4174c851a1f3b45335683bbcb6c5a7457c204585ac1d755c13ffc7df33cbc1ac247cf7f536b6f11ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e201fc93d0e137f2922bd2ef940bebeed889eed5513c5d7c8158492278ec8c13c669dcd31d2d33474656406acf43722102f0989ac6cdb60e6fb737f3e97a5619"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 07:51:52.346344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:51:52.346695) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1482671290.691588-1639381335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 07:51:52.431397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 07:51:52.432134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 07:51:52.432449) ========= ========= Total master_lag: 0.21 =========