builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-064 starttime: 1482972602.18 results: success (0) buildid: 20161228160136 builduid: 2cac69f328644f2b8a6ebff0882aeaf2 revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.184110) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.184631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.184918) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.200514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.200817) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 16:50:03-- 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% 219M=0s 2016-12-28 16:50:04 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.190511 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.404804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.405110) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019532 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.454500) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:02.454848) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --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 b882b98ee9ddba735a0a3a57623e0b9d9fc7742a --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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 16:50:04,186 truncating revision to first 12 chars 2016-12-28 16:50:04,186 Setting DEBUG logging. 2016-12-28 16:50:04,186 attempt 1/10 2016-12-28 16:50:04,187 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b882b98ee9dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 16:50:04,504 unpacking tar archive at: mozilla-central-b882b98ee9dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.629371 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:03.098955) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:03.099285) ========= script_repo_revision: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:03.099701) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:03.099976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 16:50:03.112737) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-28 16:50:03.113188) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:50:04 INFO - MultiFileLogger online at 20161228 16:50:04 in /builds/slave/test 16:50:04 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 16:50:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:50:04 INFO - {'append_to_log': False, 16:50:04 INFO - 'base_work_dir': '/builds/slave/test', 16:50:04 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:50:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:50:04 INFO - 'branch': 'Firefox-Non-PGO', 16:50:04 INFO - 'buildbot_json_path': 'buildprops.json', 16:50:04 INFO - 'config_files': ('talos/linux_config.py',), 16:50:04 INFO - 'default_actions': ('clobber', 16:50:04 INFO - 'read-buildbot-config', 16:50:04 INFO - 'download-and-extract', 16:50:04 INFO - 'populate-webroot', 16:50:04 INFO - 'create-virtualenv', 16:50:04 INFO - 'install', 16:50:04 INFO - 'run-tests'), 16:50:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:50:04 INFO - 'download_minidump_stackwalk': True, 16:50:04 INFO - 'download_symbols': 'ondemand', 16:50:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:50:04 INFO - 'tooltool.py': '/tools/tooltool.py', 16:50:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:50:04 INFO - '/tools/misc-python/virtualenv.py')}, 16:50:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:50:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:50:04 INFO - 'installer_path': 'installer.exe', 16:50:04 INFO - 'log_level': 'info', 16:50:04 INFO - 'log_name': 'talos', 16:50:04 INFO - 'log_to_console': True, 16:50:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:50:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:50:04 INFO - 'opt_config_files': (), 16:50:04 INFO - 'pip_index': False, 16:50:04 INFO - 'sps_profile': False, 16:50:04 INFO - 'sps_profile_interval': 0, 16:50:04 INFO - 'suite': 'dromaeojs', 16:50:04 INFO - 'system_bits': '32', 16:50:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:50:04 INFO - 'title': 'talos-linux64-ix-064', 16:50:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:50:04 INFO - 'use_talos_json': True, 16:50:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:50:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:50:04 INFO - 'work_dir': 'build'} 16:50:04 INFO - [mozharness: 2016-12-29 00:50:04.941493Z] Running clobber step. 16:50:04 INFO - Running pre-action listener: _resource_record_pre_action 16:50:04 INFO - Running main action method: clobber 16:50:04 INFO - rmtree: /builds/slave/test/build 16:50:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:50:05 INFO - Running post-action listener: _resource_record_post_action 16:50:05 INFO - [mozharness: 2016-12-29 00:50:05.403024Z] Finished clobber step (success) 16:50:05 INFO - [mozharness: 2016-12-29 00:50:05.403125Z] Running read-buildbot-config step. 16:50:05 INFO - Running pre-action listener: _resource_record_pre_action 16:50:05 INFO - Running main action method: read_buildbot_config 16:50:05 INFO - Using buildbot properties: 16:50:05 INFO - { 16:50:05 INFO - "project": "", 16:50:05 INFO - "product": "firefox", 16:50:05 INFO - "script_repo_revision": "production", 16:50:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:50:05 INFO - "repository": "", 16:50:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:50:05 INFO - "buildid": "20161228160136", 16:50:05 INFO - "pgo_build": "False", 16:50:05 INFO - "basedir": "/builds/slave/test", 16:50:05 INFO - "buildnumber": 755, 16:50:05 INFO - "slavename": "talos-linux64-ix-064", 16:50:05 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:50:05 INFO - "platform": "ubuntu64_hw", 16:50:05 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:50:05 INFO - "branch": "mozilla-central", 16:50:05 INFO - "repo_path": "mozilla-central", 16:50:05 INFO - "revision": "b882b98ee9ddba735a0a3a57623e0b9d9fc7742a", 16:50:05 INFO - "stage_platform": "linux64", 16:50:05 INFO - "builduid": "2cac69f328644f2b8a6ebff0882aeaf2", 16:50:05 INFO - "slavebuilddir": "test" 16:50:05 INFO - } 16:50:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 16:50:05 INFO - Running post-action listener: _resource_record_post_action 16:50:05 INFO - [mozharness: 2016-12-29 00:50:05.405558Z] Finished read-buildbot-config step (success) 16:50:05 INFO - [mozharness: 2016-12-29 00:50:05.405647Z] Running download-and-extract step. 16:50:05 INFO - Running pre-action listener: _resource_record_pre_action 16:50:05 INFO - Running main action method: download_and_extract 16:50:05 INFO - mkdir: /builds/slave/test/build/tests 16:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:05 INFO - trying https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:50:05 INFO - Downloading https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/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 16:50:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/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 16:50:05 INFO - Downloaded 1361 bytes. 16:50:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 16:50:05 INFO - Using the following test package requirements: 16:50:05 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:50:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:50:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:50:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'jsshell-linux-x86_64.zip'], 16:50:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:50:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 16:50:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:50:05 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:50:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:50:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 16:50:05 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:50:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:50:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:50:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:50:05 INFO - Fetch https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:50:06 INFO - Expected file size: 20242339 16:50:06 INFO - Obtained file size: 20242339 16:50:10 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 16:50:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 16:50:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:50:10 INFO - Fetch https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 16:50:11 INFO - Expected file size: 20242339 16:50:11 INFO - Obtained file size: 20242339 16:50:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 16:50:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:50:15 INFO - Fetch https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 16:50:16 INFO - Expected file size: 14409776 16:50:16 INFO - Obtained file size: 14409776 16:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:16 INFO - trying https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:50:16 INFO - Downloading https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/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 16:50:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/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 16:50:18 INFO - Downloaded 55902834 bytes. 16:50:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:50:18 INFO - mkdir: /builds/slave/test/properties 16:50:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:50:18 INFO - Writing to file /builds/slave/test/properties/build_url 16:50:18 INFO - Contents: 16:50:18 INFO - build_url:https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 16:50:18 INFO - Running post-action listener: _resource_record_post_action 16:50:18 INFO - Running post-action listener: set_extra_try_arguments 16:50:18 INFO - [mozharness: 2016-12-29 00:50:18.950140Z] Finished download-and-extract step (success) 16:50:18 INFO - [mozharness: 2016-12-29 00:50:18.950509Z] Running populate-webroot step. 16:50:18 INFO - Running pre-action listener: _resource_record_pre_action 16:50:18 INFO - Running main action method: populate_webroot 16:50:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:50:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:50:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:50:18 INFO - '--tppagecycles', 16:50:18 INFO - '7'], 16:50:18 INFO - 'tests': ['tsvgm']}}, 16:50:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:50:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:50:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:50:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:50:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:50:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['damp', 'tps']}, 16:50:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'tests': ['damp', 'tps']}, 16:50:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['dromaeo_dom']}, 16:50:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:50:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['basic_compositor_video']}, 16:50:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:50:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'other-e10s': {'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['a11yr', 16:50:18 INFO - 'ts_paint', 16:50:18 INFO - 'tpaint', 16:50:18 INFO - 'sessionrestore', 16:50:18 INFO - 'sessionrestore_no_auto_restore', 16:50:18 INFO - 'tabpaint']}, 16:50:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['tsvgx', 16:50:18 INFO - 'tsvgr_opacity', 16:50:18 INFO - 'tart', 16:50:18 INFO - 'tscrollx', 16:50:18 INFO - 'cart', 16:50:18 INFO - 'tsvg_static']}, 16:50:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:50:18 INFO - 'tsvgr_opacity', 16:50:18 INFO - 'tart', 16:50:18 INFO - 'tscrollx', 16:50:18 INFO - 'cart', 16:50:18 INFO - 'tsvg_static']}, 16:50:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'talos_options': ['--disable-e10s'], 16:50:18 INFO - 'tests': ['tp5o']}, 16:50:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:50:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'talos_options': ['--disable-e10s', 16:50:18 INFO - '--xperf_path', 16:50:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:18 INFO - 'tests': ['tp5n']}, 16:50:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:50:18 INFO - 'talos_options': ['--xperf_path', 16:50:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:50:18 INFO - 'tests': ['tp5n']}}, 16:50:18 INFO - 'talos.zip': {'path': '', 16:50:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:50:18 INFO - Running post-action listener: _resource_record_post_action 16:50:18 INFO - [mozharness: 2016-12-29 00:50:18.967572Z] Finished populate-webroot step (success) 16:50:18 INFO - [mozharness: 2016-12-29 00:50:18.967665Z] Running create-virtualenv step. 16:50:18 INFO - Running pre-action listener: _resource_record_pre_action 16:50:18 INFO - Running main action method: create_virtualenv 16:50:18 INFO - Creating virtualenv /builds/slave/test/build/venv 16:50:18 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 16:50:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:50:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:50:19 INFO - Using real prefix '/usr' 16:50:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:50:19 INFO - Installing distribute.............................................................................................................................................................................................done. 16:50:21 INFO - Installing pip.................done. 16:50:21 INFO - Return code: 0 16:50:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:50:21 INFO - Reading from file tmpfile_stdout 16:50:21 INFO - Output received: 16:50:21 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) 16:50:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:21 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:21 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 16:50:21 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 16:50:21 INFO - Using env: {'DISPLAY': ':0', 16:50:21 INFO - 'HOME': '/home/cltbld', 16:50:21 INFO - 'LANG': 'en_US.UTF-8', 16:50:21 INFO - 'LANGUAGE': 'en_US:en', 16:50:21 INFO - 'LOGNAME': 'cltbld', 16:50:21 INFO - 'MAIL': '/var/mail/cltbld', 16:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:21 INFO - 'MOZ_NO_REMOTE': '1', 16:50:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:21 INFO - 'NO_EM_RESTART': '1', 16:50:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:21 INFO - 'PWD': '/builds/slave/test', 16:50:21 INFO - 'SHELL': '/bin/bash', 16:50:21 INFO - 'SHLVL': '1', 16:50:21 INFO - 'TERM': 'linux', 16:50:21 INFO - 'TMOUT': '86400', 16:50:21 INFO - 'USER': 'cltbld', 16:50:21 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:21 INFO - '_': '/tools/buildbot/bin/python'} 16:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:21 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 16:50:21 INFO - Cleaning up... 16:50:21 INFO - Return code: 0 16:50:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:21 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:21 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 16:50:21 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 16:50:22 INFO - Using env: {'DISPLAY': ':0', 16:50:22 INFO - 'HOME': '/home/cltbld', 16:50:22 INFO - 'LANG': 'en_US.UTF-8', 16:50:22 INFO - 'LANGUAGE': 'en_US:en', 16:50:22 INFO - 'LOGNAME': 'cltbld', 16:50:22 INFO - 'MAIL': '/var/mail/cltbld', 16:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:22 INFO - 'MOZ_NO_REMOTE': '1', 16:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:22 INFO - 'NO_EM_RESTART': '1', 16:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:22 INFO - 'PWD': '/builds/slave/test', 16:50:22 INFO - 'SHELL': '/bin/bash', 16:50:22 INFO - 'SHLVL': '1', 16:50:22 INFO - 'TERM': 'linux', 16:50:22 INFO - 'TMOUT': '86400', 16:50:22 INFO - 'USER': 'cltbld', 16:50:22 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:22 INFO - '_': '/tools/buildbot/bin/python'} 16:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:22 INFO - Downloading/unpacking psutil>=3.1.1 16:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:50:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:24 INFO - Installing collected packages: psutil 16:50:24 INFO - Running setup.py install for psutil 16:50:24 INFO - building 'psutil._psutil_linux' extension 16:50:24 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 16:50:24 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 16:50:24 INFO - building 'psutil._psutil_posix' extension 16:50:24 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 16:50:24 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 16:50:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:50:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:50:24 INFO - Successfully installed psutil 16:50:24 INFO - Cleaning up... 16:50:24 INFO - Return code: 0 16:50:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:50:24 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')]} 16:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:24 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')]} 16:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:24 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:24 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 16:50:24 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 16:50:24 INFO - Using env: {'DISPLAY': ':0', 16:50:24 INFO - 'HOME': '/home/cltbld', 16:50:24 INFO - 'LANG': 'en_US.UTF-8', 16:50:24 INFO - 'LANGUAGE': 'en_US:en', 16:50:24 INFO - 'LOGNAME': 'cltbld', 16:50:24 INFO - 'MAIL': '/var/mail/cltbld', 16:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:24 INFO - 'MOZ_NO_REMOTE': '1', 16:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:24 INFO - 'NO_EM_RESTART': '1', 16:50:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:24 INFO - 'PWD': '/builds/slave/test', 16:50:24 INFO - 'SHELL': '/bin/bash', 16:50:24 INFO - 'SHLVL': '1', 16:50:24 INFO - 'TERM': 'linux', 16:50:24 INFO - 'TMOUT': '86400', 16:50:24 INFO - 'USER': 'cltbld', 16:50:24 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:24 INFO - '_': '/tools/buildbot/bin/python'} 16:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:50:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:50:27 INFO - Installing collected packages: mozsystemmonitor 16:50:27 INFO - Running setup.py install for mozsystemmonitor 16:50:27 INFO - Successfully installed mozsystemmonitor 16:50:27 INFO - Cleaning up... 16:50:27 INFO - Return code: 0 16:50:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:50:27 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')]} 16:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:27 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')]} 16:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:27 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:27 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 16:50:27 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 16:50:27 INFO - Using env: {'DISPLAY': ':0', 16:50:27 INFO - 'HOME': '/home/cltbld', 16:50:27 INFO - 'LANG': 'en_US.UTF-8', 16:50:27 INFO - 'LANGUAGE': 'en_US:en', 16:50:27 INFO - 'LOGNAME': 'cltbld', 16:50:27 INFO - 'MAIL': '/var/mail/cltbld', 16:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:27 INFO - 'MOZ_NO_REMOTE': '1', 16:50:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:27 INFO - 'NO_EM_RESTART': '1', 16:50:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:27 INFO - 'PWD': '/builds/slave/test', 16:50:27 INFO - 'SHELL': '/bin/bash', 16:50:27 INFO - 'SHLVL': '1', 16:50:27 INFO - 'TERM': 'linux', 16:50:27 INFO - 'TMOUT': '86400', 16:50:27 INFO - 'USER': 'cltbld', 16:50:27 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:27 INFO - '_': '/tools/buildbot/bin/python'} 16:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:27 INFO - Downloading/unpacking jsonschema==2.5.1 16:50:27 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 16:50:27 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 16:50:27 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 16:50:27 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 16:50:27 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 16:50:27 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 16:50:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:50:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:50: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 16:50:29 INFO - Downloading functools32-3.2.3-2.tar.gz 16:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:50:29 INFO - warning: no files found matching '*.txt' 16:50:29 INFO - no previously-included directories found matching 'build' 16:50:29 INFO - no previously-included directories found matching 'dist' 16:50:29 INFO - no previously-included directories found matching '.git*' 16:50:29 INFO - Installing collected packages: jsonschema, functools32 16:50:29 INFO - Running setup.py install for functools32 16:50:29 INFO - warning: no files found matching '*.txt' 16:50:29 INFO - no previously-included directories found matching 'build' 16:50:29 INFO - no previously-included directories found matching 'dist' 16:50:29 INFO - no previously-included directories found matching '.git*' 16:50:29 INFO - Successfully installed jsonschema functools32 16:50:29 INFO - Cleaning up... 16:50:30 INFO - Return code: 0 16:50:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:30 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:30 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 16:50:30 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 16:50:30 INFO - Using env: {'DISPLAY': ':0', 16:50:30 INFO - 'HOME': '/home/cltbld', 16:50:30 INFO - 'LANG': 'en_US.UTF-8', 16:50:30 INFO - 'LANGUAGE': 'en_US:en', 16:50:30 INFO - 'LOGNAME': 'cltbld', 16:50:30 INFO - 'MAIL': '/var/mail/cltbld', 16:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:30 INFO - 'MOZ_NO_REMOTE': '1', 16:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:30 INFO - 'NO_EM_RESTART': '1', 16:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:30 INFO - 'PWD': '/builds/slave/test', 16:50:30 INFO - 'SHELL': '/bin/bash', 16:50:30 INFO - 'SHLVL': '1', 16:50:30 INFO - 'TERM': 'linux', 16:50:30 INFO - 'TMOUT': '86400', 16:50:30 INFO - 'USER': 'cltbld', 16:50:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:30 INFO - '_': '/tools/buildbot/bin/python'} 16:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:50:30 INFO - Cleaning up... 16:50:30 INFO - Return code: 0 16:50:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:30 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:30 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 16:50:30 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 16:50:30 INFO - Using env: {'DISPLAY': ':0', 16:50:30 INFO - 'HOME': '/home/cltbld', 16:50:30 INFO - 'LANG': 'en_US.UTF-8', 16:50:30 INFO - 'LANGUAGE': 'en_US:en', 16:50:30 INFO - 'LOGNAME': 'cltbld', 16:50:30 INFO - 'MAIL': '/var/mail/cltbld', 16:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:30 INFO - 'MOZ_NO_REMOTE': '1', 16:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:30 INFO - 'NO_EM_RESTART': '1', 16:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:30 INFO - 'PWD': '/builds/slave/test', 16:50:30 INFO - 'SHELL': '/bin/bash', 16:50:30 INFO - 'SHLVL': '1', 16:50:30 INFO - 'TERM': 'linux', 16:50:30 INFO - 'TMOUT': '86400', 16:50:30 INFO - 'USER': 'cltbld', 16:50:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:30 INFO - '_': '/tools/buildbot/bin/python'} 16:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:30 INFO - Downloading/unpacking blobuploader==1.2.4 16:50:30 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 16:50:30 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 16:50:30 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 16:50:30 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 16:50:30 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 16:50:30 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 16:50:32 INFO - Downloading blobuploader-1.2.4.tar.gz 16:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:50:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:50:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:32 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 16:50:33 INFO - Downloading docopt-0.6.1.tar.gz 16:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:50:33 INFO - Installing collected packages: blobuploader, requests, docopt 16:50:33 INFO - Running setup.py install for blobuploader 16:50:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:50:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:50:33 INFO - Running setup.py install for requests 16:50:33 INFO - Running setup.py install for docopt 16:50:33 INFO - Successfully installed blobuploader requests docopt 16:50:33 INFO - Cleaning up... 16:50:33 INFO - Return code: 0 16:50:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:33 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')]} 16:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:33 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')]} 16:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:33 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:33 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 16:50:33 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 16:50:33 INFO - Using env: {'DISPLAY': ':0', 16:50:33 INFO - 'HOME': '/home/cltbld', 16:50:33 INFO - 'LANG': 'en_US.UTF-8', 16:50:33 INFO - 'LANGUAGE': 'en_US:en', 16:50:33 INFO - 'LOGNAME': 'cltbld', 16:50:33 INFO - 'MAIL': '/var/mail/cltbld', 16:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:33 INFO - 'MOZ_NO_REMOTE': '1', 16:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:33 INFO - 'NO_EM_RESTART': '1', 16:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:33 INFO - 'PWD': '/builds/slave/test', 16:50:33 INFO - 'SHELL': '/bin/bash', 16:50:33 INFO - 'SHLVL': '1', 16:50:33 INFO - 'TERM': 'linux', 16:50:33 INFO - 'TMOUT': '86400', 16:50:33 INFO - 'USER': 'cltbld', 16:50:33 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:33 INFO - '_': '/tools/buildbot/bin/python'} 16:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:50:34 INFO - Running setup.py (path:/tmp/pip-cHDYQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:50:34 INFO - Running setup.py (path:/tmp/pip-Iw5EHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:50:34 INFO - Running setup.py (path:/tmp/pip-0joNi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:50:34 INFO - Running setup.py (path:/tmp/pip-uysm6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:50:34 INFO - Running setup.py (path:/tmp/pip-lqEVxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:50:34 INFO - Running setup.py (path:/tmp/pip-byID5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:50:34 INFO - Running setup.py (path:/tmp/pip-QplT0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:50:34 INFO - Running setup.py (path:/tmp/pip-YQ1zMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:50:34 INFO - Running setup.py (path:/tmp/pip-exG6J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:50:34 INFO - Running setup.py (path:/tmp/pip-OP66Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:50:34 INFO - Running setup.py (path:/tmp/pip-h8LQov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:50:34 INFO - Running setup.py (path:/tmp/pip-nhQvfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:50:34 INFO - Running setup.py (path:/tmp/pip-ksWXDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:50:35 INFO - Running setup.py (path:/tmp/pip-oLk9W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:35 INFO - Running setup.py (path:/tmp/pip-zndlHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:50:35 INFO - Running setup.py (path:/tmp/pip-2Vf3dY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:50:35 INFO - Running setup.py (path:/tmp/pip-9VOt6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:50:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:50:35 INFO - Running setup.py install for manifestparser 16:50:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:50:35 INFO - Running setup.py install for mozcrash 16:50:35 INFO - Running setup.py install for mozdebug 16:50:35 INFO - Running setup.py install for mozdevice 16:50:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:50:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:50:35 INFO - Running setup.py install for mozfile 16:50:35 INFO - Running setup.py install for mozhttpd 16:50:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:50:35 INFO - Running setup.py install for mozinfo 16:50:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for mozInstall 16:50:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for mozleak 16:50:36 INFO - Running setup.py install for mozlog 16:50:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for moznetwork 16:50:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for mozprocess 16:50:36 INFO - Running setup.py install for mozprofile 16:50:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for mozrunner 16:50:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:50:36 INFO - Running setup.py install for mozscreenshot 16:50:36 INFO - Running setup.py install for moztest 16:50:37 INFO - Running setup.py install for mozversion 16:50:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:50:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:50:37 INFO - Cleaning up... 16:50:37 INFO - Return code: 0 16:50:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:37 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')]} 16:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:37 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')]} 16:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:37 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:37 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 16:50:37 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 16:50:37 INFO - Using env: {'DISPLAY': ':0', 16:50:37 INFO - 'HOME': '/home/cltbld', 16:50:37 INFO - 'LANG': 'en_US.UTF-8', 16:50:37 INFO - 'LANGUAGE': 'en_US:en', 16:50:37 INFO - 'LOGNAME': 'cltbld', 16:50:37 INFO - 'MAIL': '/var/mail/cltbld', 16:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:37 INFO - 'MOZ_NO_REMOTE': '1', 16:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:37 INFO - 'NO_EM_RESTART': '1', 16:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:37 INFO - 'PWD': '/builds/slave/test', 16:50:37 INFO - 'SHELL': '/bin/bash', 16:50:37 INFO - 'SHLVL': '1', 16:50:37 INFO - 'TERM': 'linux', 16:50:37 INFO - 'TMOUT': '86400', 16:50:37 INFO - 'USER': 'cltbld', 16:50:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:37 INFO - '_': '/tools/buildbot/bin/python'} 16:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:50:37 INFO - Running setup.py (path:/tmp/pip-XF_kzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:50:37 INFO - Running setup.py (path:/tmp/pip-Q8M6fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:50:37 INFO - Running setup.py (path:/tmp/pip-1Wm_Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:50:37 INFO - Running setup.py (path:/tmp/pip-3Ah3bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:50:37 INFO - Running setup.py (path:/tmp/pip-qx1jZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:50:37 INFO - Running setup.py (path:/tmp/pip-73Zrfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:50:37 INFO - Running setup.py (path:/tmp/pip-TLgaP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:50:37 INFO - Running setup.py (path:/tmp/pip-PNdDA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:50:37 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)) 16:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:50:37 INFO - Running setup.py (path:/tmp/pip-PGfiMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:50:38 INFO - Running setup.py (path:/tmp/pip-sELOVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:50:38 INFO - Running setup.py (path:/tmp/pip-BY0e7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:50:38 INFO - Running setup.py (path:/tmp/pip-Fi9MD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:50:38 INFO - Running setup.py (path:/tmp/pip-Zi9vBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:50:38 INFO - Running setup.py (path:/tmp/pip-cRePWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:38 INFO - Running setup.py (path:/tmp/pip-z1cw8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:50:38 INFO - Running setup.py (path:/tmp/pip-IeyAvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:50:38 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)) 16:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:50:38 INFO - Running setup.py (path:/tmp/pip-aMuAv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:50:38 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)) 16:50:38 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)) 16:50:38 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)) 16:50:38 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)) 16:50:38 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)) 16:50:38 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)) 16:50:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:50:38 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 16:50:38 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 16:50:38 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 16:50:38 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 16:50:38 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 16:50:38 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 16:50:40 INFO - Downloading blessings-1.6.tar.gz 16:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:50:40 INFO - Installing collected packages: blessings 16:50:40 INFO - Running setup.py install for blessings 16:50:40 INFO - Successfully installed blessings 16:50:40 INFO - Cleaning up... 16:50:40 INFO - Return code: 0 16:50:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:50:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:50:41 INFO - Reading from file tmpfile_stdout 16:50:41 INFO - Current package versions: 16:50:41 INFO - argparse == 1.2.1 16:50:41 INFO - blessings == 1.6 16:50:41 INFO - blobuploader == 1.2.4 16:50:41 INFO - docopt == 0.6.1 16:50:41 INFO - functools32 == 3.2.3-2 16:50:41 INFO - jsonschema == 2.5.1 16:50:41 INFO - manifestparser == 1.1 16:50:41 INFO - mozInstall == 1.12 16:50:41 INFO - mozcrash == 1.0 16:50:41 INFO - mozdebug == 0.1 16:50:41 INFO - mozdevice == 0.49 16:50:41 INFO - mozfile == 1.2 16:50:41 INFO - mozhttpd == 0.7 16:50:41 INFO - mozinfo == 0.9 16:50:41 INFO - mozleak == 0.1 16:50:41 INFO - mozlog == 3.4 16:50:41 INFO - moznetwork == 0.27 16:50:41 INFO - mozprocess == 0.23 16:50:41 INFO - mozprofile == 0.28 16:50:41 INFO - mozrunner == 6.13 16:50:41 INFO - mozscreenshot == 0.1 16:50:41 INFO - mozsystemmonitor == 0.3 16:50:41 INFO - moztest == 0.7 16:50:41 INFO - mozversion == 1.4 16:50:41 INFO - psutil == 3.1.1 16:50:41 INFO - requests == 1.2.3 16:50:41 INFO - wsgiref == 0.1.2 16:50:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:41 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:41 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 16:50:41 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 16:50:41 INFO - Using env: {'DISPLAY': ':0', 16:50:41 INFO - 'HOME': '/home/cltbld', 16:50:41 INFO - 'LANG': 'en_US.UTF-8', 16:50:41 INFO - 'LANGUAGE': 'en_US:en', 16:50:41 INFO - 'LOGNAME': 'cltbld', 16:50:41 INFO - 'MAIL': '/var/mail/cltbld', 16:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:41 INFO - 'MOZ_NO_REMOTE': '1', 16:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:41 INFO - 'NO_EM_RESTART': '1', 16:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:41 INFO - 'PWD': '/builds/slave/test', 16:50:41 INFO - 'SHELL': '/bin/bash', 16:50:41 INFO - 'SHLVL': '1', 16:50:41 INFO - 'TERM': 'linux', 16:50:41 INFO - 'TMOUT': '86400', 16:50:41 INFO - 'USER': 'cltbld', 16:50:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:41 INFO - '_': '/tools/buildbot/bin/python'} 16:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 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)) 16:50:41 INFO - Cleaning up... 16:50:41 INFO - Return code: 0 16:50:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:41 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 0x14db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15eba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', '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 16:50:41 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 16:50:41 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 16:50:41 INFO - Using env: {'DISPLAY': ':0', 16:50:41 INFO - 'HOME': '/home/cltbld', 16:50:41 INFO - 'LANG': 'en_US.UTF-8', 16:50:41 INFO - 'LANGUAGE': 'en_US:en', 16:50:41 INFO - 'LOGNAME': 'cltbld', 16:50:41 INFO - 'MAIL': '/var/mail/cltbld', 16:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:41 INFO - 'MOZ_NO_REMOTE': '1', 16:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:41 INFO - 'NO_EM_RESTART': '1', 16:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:41 INFO - 'PWD': '/builds/slave/test', 16:50:41 INFO - 'SHELL': '/bin/bash', 16:50:41 INFO - 'SHLVL': '1', 16:50:41 INFO - 'TERM': 'linux', 16:50:41 INFO - 'TMOUT': '86400', 16:50:41 INFO - 'USER': 'cltbld', 16:50:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:41 INFO - '_': '/tools/buildbot/bin/python'} 16:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:50:41 INFO - Cleaning up... 16:50:41 INFO - Return code: 0 16:50:41 INFO - Running post-action listener: _resource_record_post_action 16:50:41 INFO - Running post-action listener: _start_resource_monitoring 16:50:41 INFO - Starting resource monitoring. 16:50:41 INFO - [mozharness: 2016-12-29 00:50:41.549717Z] Finished create-virtualenv step (success) 16:50:41 INFO - [mozharness: 2016-12-29 00:50:41.550289Z] Running install step. 16:50:41 INFO - Running pre-action listener: _resource_record_pre_action 16:50:41 INFO - Running main action method: install 16:50:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:50:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:50:41 INFO - Reading from file tmpfile_stdout 16:50:41 INFO - Detecting whether we're running mozinstall >=1.0... 16:50:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:50:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:50:41 INFO - Reading from file tmpfile_stdout 16:50:41 INFO - Output received: 16:50:41 INFO - Usage: mozinstall [options] installer 16:50:41 INFO - Options: 16:50:41 INFO - -h, --help show this help message and exit 16:50:41 INFO - -d DEST, --destination=DEST 16:50:41 INFO - Directory to install application into. [default: 16:50:41 INFO - "/builds/slave/test"] 16:50:41 INFO - --app=APP Application being installed. [default: firefox] 16:50:41 INFO - mkdir: /builds/slave/test/build/application 16:50:41 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'] 16:50:41 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 16:50:57 INFO - Reading from file tmpfile_stdout 16:50:57 INFO - Output received: 16:50:57 INFO - /builds/slave/test/build/application/firefox/firefox 16:50:57 INFO - Running post-action listener: _resource_record_post_action 16:50:57 INFO - [mozharness: 2016-12-29 00:50:57.924368Z] Finished install step (success) 16:50:57 INFO - [mozharness: 2016-12-29 00:50:57.924615Z] Running run-tests step. 16:50:57 INFO - Running pre-action listener: _resource_record_pre_action 16:50:57 INFO - Running main action method: run_tests 16:50:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:50:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:50:57 INFO - Python 2.7.3 16:50:57 INFO - Return code: 0 16:50:57 INFO - grabbing minidump binary from tooltool 16:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:50:57 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 0x18d6850>, '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 0x184b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184bb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:50:57 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 16:50:57 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 16:50:57 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 16:50:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:50:58 INFO - Return code: 0 16:50:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:50:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:50:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:50:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:50:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:50:58 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/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 16:50:58 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/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 16:50:58 INFO - Using env: {'DISPLAY': ':0', 16:50:58 INFO - 'HOME': '/home/cltbld', 16:50:58 INFO - 'LANG': 'en_US.UTF-8', 16:50:58 INFO - 'LANGUAGE': 'en_US:en', 16:50:58 INFO - 'LOGNAME': 'cltbld', 16:50:58 INFO - 'MAIL': '/var/mail/cltbld', 16:50:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:50:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:58 INFO - 'MOZ_NO_REMOTE': '1', 16:50:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:50:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:50:58 INFO - 'NO_EM_RESTART': '1', 16:50:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:58 INFO - 'PWD': '/builds/slave/test', 16:50:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:50:58 INFO - 'SHELL': '/bin/bash', 16:50:58 INFO - 'SHLVL': '1', 16:50:58 INFO - 'TERM': 'linux', 16:50:58 INFO - 'TMOUT': '86400', 16:50:58 INFO - 'USER': 'cltbld', 16:50:58 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087', 16:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:58 INFO - '_': '/tools/buildbot/bin/python'} 16:50:58 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/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 16:50:58 INFO - mozversion application_buildid: 20161228160136 16:50:58 INFO - mozversion application_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:50:58 INFO - mozversion application_display_name: Nightly 16:50:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:50:58 INFO - mozversion application_name: Firefox 16:50:58 INFO - mozversion application_remotingname: firefox 16:50:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:50:58 INFO - mozversion application_vendor: Mozilla 16:50:58 INFO - mozversion application_version: 53.0a1 16:50:58 INFO - mozversion platform_buildid: 20161228160136 16:50:58 INFO - mozversion platform_changeset: b882b98ee9ddba735a0a3a57623e0b9d9fc7742a 16:50:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:50:58 INFO - mozversion platform_version: 53.0a1 16:50:58 INFO - using testdate: 1482972658 16:50:58 INFO - actual date: 1482972658 16:50:58 INFO - starting webserver on 'localhost:50891' 16:50:58 INFO - SUITE-START | Running 2 tests 16:50:58 INFO - TEST-START | dromaeo_css 16:50:58 INFO - operating with platform_type : linux_ 16:50:58 INFO - Initialising browser for dromaeo_css test... 16:50:58 INFO - TEST-INFO | started process 19417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnILvDb/profile http://localhost:50891/getInfo.html) 16:50:58 INFO - PROCESS | 19417 | 1482972658691 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 16:51:02 INFO - PROCESS | 19417 | __metrics Screen width/height:1600/1200 16:51:02 INFO - PROCESS | 19417 | colorDepth:24 16:51:02 INFO - PROCESS | 19417 | Browser inner width/height: 1024/697 16:51:02 INFO - PROCESS | 19417 | __metrics 16:51:03 INFO - PROCESS | 19417 | 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" 16:51:03 INFO - PROCESS | 19417 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:03 INFO - PROCESS | 19417 | console.error: PushService: 16:51:03 INFO - PROCESS | 19417 | stateChangeProcessEnqueue: Error transitioning state 16:51:03 INFO - PROCESS | 19417 | UnknownError 16:51:04 INFO - TEST-INFO | 19417: exit 0 16:51:04 INFO - Browser initialized. 16:51:04 INFO - Running cycle 1/1 for dromaeo_css test... 16:51:04 INFO - TEST-INFO | started process 19500 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnILvDb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:16 INFO - PROCESS | 19500 | RSS: Main: 151076864 16:51:16 INFO - PROCESS | 19500 | 16:54:21 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50891/tests/dromaeo/cssquery-ext.html) 16:54:22 INFO - PROCESS | 19500 | RSS: Main: 283680768 16:54:22 INFO - PROCESS | 19500 | 16:56:05 INFO - PROCESS | 19500 | ************************* 16:56:05 INFO - PROCESS | 19500 | A coding exception was thrown and uncaught in a Task. 16:56:05 INFO - PROCESS | 19500 | 16:56:05 INFO - PROCESS | 19500 | Full message: ReferenceError: fetch is not defined 16:56:05 INFO - PROCESS | 19500 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:56:05 INFO - PROCESS | 19500 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:56:05 INFO - PROCESS | 19500 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:56:05 INFO - PROCESS | 19500 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:56:05 INFO - PROCESS | 19500 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:56:05 INFO - PROCESS | 19500 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:56:05 INFO - PROCESS | 19500 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:56:05 INFO - PROCESS | 19500 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:56:05 INFO - PROCESS | 19500 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:56:05 INFO - PROCESS | 19500 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:56:05 INFO - PROCESS | 19500 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:56:05 INFO - PROCESS | 19500 | 16:56:05 INFO - PROCESS | 19500 | ************************* 16:57:25 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-ext.html (next: http://localhost:50891/tests/dromaeo/cssquery-jquery.html) 16:57:26 INFO - PROCESS | 19500 | RSS: Main: 240525312 16:57:26 INFO - PROCESS | 19500 | 17:00:28 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50891/tests/dromaeo/cssquery-mootools.html) 17:00:29 INFO - PROCESS | 19500 | RSS: Main: 182534144 17:00:29 INFO - PROCESS | 19500 | 17:03:31 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50891/tests/dromaeo/cssquery-prototype.html) 17:03:32 INFO - PROCESS | 19500 | RSS: Main: 184324096 17:03:32 INFO - PROCESS | 19500 | 17:06:35 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50891/tests/dromaeo/cssquery-yui.html) 17:06:35 INFO - PROCESS | 19500 | RSS: Main: 183967744 17:06:35 INFO - PROCESS | 19500 | 17:09:38 INFO - PROCESS | 19500 | Cycle 1(1): loaded http://localhost:50891/tests/dromaeo/cssquery-yui.html (next: http://localhost:50891/tests/dromaeo/cssquery-dojo.html) 17:09:39 INFO - PROCESS | 19500 | RSS: Main: 282308608 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | __start_tp_report 17:09:39 INFO - PROCESS | 19500 | _x_x_mozilla_page_load 17:09:39 INFO - PROCESS | 19500 | _x_x_mozilla_page_load_details 17:09:39 INFO - PROCESS | 19500 | |i|pagename|runs| 17:09:39 INFO - PROCESS | 19500 | |0;dojo.html;2531.405119796848;2544.592865141578;2563.9359016024605;2573.0994152046787;2574.768270855624;4626.930596041054;4675.1351000065015;4686.390769200004;4715.41057367829;4726.243800991838;4746.881327966794;4761.40482439695;4784.043191361724;4809.230523116302;4848.515148485149;4337.718048326865;4356.7821608919585;4408.8016039278145;4452.6215271701785;4455.866324010285;4491.348754430605;4514.480834704018;4521.5930566249035;4522.276435770261;4530.4789949155775;4313.61177494027;4443.844465443726;4467.597916187542;4512.4133862597655;4516.458025036984;4327.567243275647;4396.428464299653;4403.493598236212;4405.779711014468;4428.822847086113;4387.802548885308;4399.78001099947;4402.185595664812;4433.22418576751;4470.329450582406;4750.976245118753;4781.115493633689;4801.7118972861745;4811.903761924742;4846.975765121152;4907.7546122694075;4941.036497882914;4944.184209605411;4981.775820088104;5106.846794596168;5071.46749591295;5091.389033315989;5101.795928162869;5112.028714544224;5136.280920671109;4743.051389722069;4745.00354925466;4754.263089221176;4756.215224487921;4801.639877009238;5083.61872859537;5186.481351864783;5187.870303242374;5193.37679478465;5232.502912223333;4990.226514890198;4990.950090499121;4998.825041123543;5051.722155281494;5101.234814777813;4931.112399768076;4965.975170124127;5122.282880396748;5124.231365295161;5125.589952803842;4946.826861059918;4961.3798275215595;4995.900081998339;5001.124803159432;5066.214736715815;4945.060438516671;5007.624428167902;5013.047520971004;5016.347874776256;5074.695518268915;4218.936715949263;4245.12974840157;4290.957090429119;4297.78511074445;4316.6546676265825;3722.8883133506038;3730.776153430803;3732.925341493155;3758.4550240215563;3758.7932663703214;4317.395564620955;4320.611144996966;4323.589259020388;4344.239758042393;4367.301231802896;4975.900481990412;5010.098182327143;5012.323336349619;5070.163423035232;5142.048720986555;4259.2759230930815;4279.507856596531;4280.208161490134;4288.50682171548;4292.012837047497;4559.908801823945;4563.041761230113;4564.2240819060835;4575.9084818303445;4579.862604121881;4662.930056049162;4701.717896926195;4711.740854253049;4718.386609740712;4720.103180395714;5063.772130254074;5075.974620126875;5076.898462030665;5083.364579427572;5094.5160209780015;4681;4687.39063921688;4700.0834837207785;4714.693544919569;4800.06398752254;5013.849584512471;5019.924701129413;5021.297018417352;5026.648134630541;5067.391912970467;5107.233914912793;5108.514691104339;5119.5648369887485;5120.078385890575;5122.923156152586;4814.325994360863;4843.370361852936;4845.53967373099;4893;4905.3623029006;4945.282933974626;4992.8751781205765;5014.5737612303365;5021.447640759587;5076.695398276115;4955.281484184845;4958.181899986462;4971.577415919543;4982.825601103944;5019.498050194951;4931.753412329441;5081.466446023114;5086.109930762176;5086.593072554113;5094.5669618082875;4778.33103365536;4830.686005409637;4847.2729090636685;4857.1742803724155;4871.9269210962575 17:09:39 INFO - PROCESS | 19500 | |1;ext.html;516.0684963640629;538.5207165622595;540.953255450703;548.6817645765459;562.8817948230871;28470.288242793915;30743.385132297368;30803.99999999997;37533.624663753355;39157;29223.707762922364;29503.557446638264;29506.262343441424;29541.261468463246;29595.85202073992;7593.126790419104;7767.067951845786;7767.6116194190345;7769.883451748228;7773.2226777322385;7478.962605186994;7558.962205188994;7655.961720191392;7669.041369828771;9739.853902191473;9638.855417168714;9922.355046921963;9931;9939.55272012761;9942.353747006458;9885.901140988606;9918.80162396748;9920.652777152765;9930.404175749478;9963.551640176172;9376.906230937671;9403.62385504579;9434.05659434058;9465.66870159541;9483.383580067273;36576.63423365759;36945;36961.630383696356;36985;37009.62990370089;3656.554982903495;3711.4247291669562;3717.5353080864893;3718.1262403335195;3770.811459427018;27856.582151267543;27968.440631187263;28033.47904575771;28261.717382826115;29050;3360.462326027848;3420.1441796100285;3474.5483087198754;3479.4954731564035;3532.8056956867363;235118;235932;237073.99999999828;237638;238246;49136.75431622891;49529;49561.75219123881;49639.50360496349;49657;52016.47983520192;52550.737246313525;52651;52680;52692;11867.050635949103;12163.2702037878;12186.390680465942;12257.14200005991;12339.568115115928;8248.917510824815;8314.376421768271;8317.792055198548;8319.126491718403;8322.126176751475;13432;13882.51411200603;14159;14437.639059023608;14649.780253296209;5624.55003599711;5652.541144602029;5691.288588926384;5721.456461636138;5756.597038207286;709.5884387055495;712.4229374206806;723.8190452386904;728.7558667846225;731.5829976913109;26648;27667.861660691568;27691.861540692167;27979;28323.716762832108;778.5717855179629;779.1974266505508;781.199270747477;787.1616728184465;828.2918105020121;14022.71954560903;14779.136511105566;14782.869421817957;14898.702025959421;14906.403743850342;142000;142669;142689;142949.70502949622;142957;48446.75776621094;48661.513384866405;48750.756246218545;48755;48848;1140.9771804563861;1183.6804062902968;1185.4013723069795;1194.2237545595258;1199.2804317409484;25953.22140335793;26594.86702566475;26652.60021099724;27468.862655686593;27634.170974870787;40813.795931020155;41144.1771164581;41472;41477.792611036755;41563.584364157185;295930;296153;297612.8222689996;298234;300311;30398.848005759828;34193.31613367768;34544.82727586346;34586.48120278148;34646.30707385889;35345;40323;43305;43592.67906153653;44249.11501770011;34697.306053879285;34847.47728784019;35044;35674.28651427009;35700;34806.47790283097;35035.47446788351;35078.12304692403;35184.824075879456;35187.82406087953;34003.48994765031;34231.48652770161;34275;34636.65363346435;34883;28425.427335484546;31228.20844078465;31240.58011518475;31240.916741163994;31323;24003;24035.519289614458;24341.634875477237;24514.387140321636;24728.752712472644 17:09:39 INFO - PROCESS | 19500 | |2;jquery.html;368.42709586592855;378.7159630277293;379.80440073362223;385.3295266236749;398.4680451597118;15477.148756818371;15945.920270398661;16584.91707541462;16620.83379166208;16672.1663916804;13726.382312795924;14428.783568246485;16514;16762.07808570528;16868.156592170373;4553.317002449634;4800;4806.399200099988;4807.375041244633;4812.23004319309;3700.7594506357;3928.6267804558665;4715.6934799238115;5186.429492755794;5477.4796394342475;5567.526760225385;5574.749136288857;5577.274954255941;5577.637453565526;5580.637258578179;5314.362276526803;5413.675179489204;5421.512063914267;5447.972760136174;5587.3015873015875;5261.6842989420375;5262.394824595144;5268.446813084609;5278.287431196778;5281.102212623863;13518.188908665512;14902.552923412206;19567.41297761055;19713.802861971482;19736.506587335432;2165.058199683143;2211.1155537784853;2216.6342553478657;2236.3961730332717;2290.0954123121273;9110.5444727764;9144.40561363516;9155.633774649006;9537.093976072261;9789.412635241908;1707.5133586927666;1717.313074770102;1736.8697347698974;1737.1053907237783;1743.1545700335328;59673.70163149157;65502.67248663774;66658;68159.31840681579;68771;9584.28117891161;9740.415575065519;9768.560414781352;10146.746331341776;10345.844812327821;13823.170609763447;13840.861591384159;13845.100068495516;13859.376328065262;13869.930650346683;6288.685565721696;6471.838204044937;6602.2737498875085;6634.502412318999;6639.070530125786;5681.715914204273;5734.05388110957;5851.941480585225;5860.882782344329;5861.208736820475;4309.332053531708;4387.583179597933;4398.834308908142;4456.17560751262;4524.932126018112;1733.514615907548;1808.1953530678722;1817.4729328494852;1821.7449557062023;1882.8776129551536;723.6309482164135;762.5158024654273;768.7616838779996;770.1182146442292;792.5640897506348;15846;15852;16053.197340132947;16500;16979.320827167023;738.881778915371;741.8627553902545;750.8385697075155;752.8306131120563;767.8733009053443;8753.649854005896;9166.724998250063;9228.123328283802;9293.767655808524;9368.765780855532;330762;335956;336467;336647;336943;70252.2974770256;71393;71562;74061.25938740543;74103.62948185224;920.8526635738384;927.7634203278121;946.129560804062;956.5360800011989;961.8028304197762;15017.624559385666;15273.159976201416;15686.137262450675;15718.13550254747;15966.60083497922;33187;33308.666913330555;33957.66042339545;37623.2475350486;38274.61725382822;42053.220154271556;46348.7682561585;47369.28946065743;53051.143209987466;54363.45636543584;7493.4379921505015;7580.734674286374;7783.532988020737;7819.100803407453;7910.406719496176;10105.848412273968;10608.204384671026;11459.713507162054;11636.825447618123;11961.581344652895;9357.859632105388;9377.1091746284;9525.523723813643;9760.755981100529;9956.004399559986;9450.196733277748;9831.655892043744;9934.453605051502;10069.949650251701;10825.729356766144;9345.392549484262;9546.37948533371;9549.09283618055;9577.473238971923;9584.472853992818;7599.316061554487;7622.961885190539;7786.338161256356;7858.960705196438;8041.075276343061;7899.328557072713;9466.810663786548;9559.761005974906;9681.225501959967;9945.453000085065 17:09:39 INFO - PROCESS | 19500 | |3;mootools.html;1267.207995003123;1282.089716301426;1290.045366428843;1297.37725891572;1297.5134324628264;6926.515143939926;7341;7347.375473084788;7349.779506614804;7350.742724004647;6553.803385898431;6760.121184246053;7056.188538318095;7225.205227424979;7230.7830765077;2085.280578200522;2117.2483768262277;2119.7668256491775;2120.586485635302;2121.448423409917;2157.514559224178;2188;2197.1760589778833;2203.8126759225484;2208.3706143749037;1728.8789784715075;1786.428342930263;2072.7823578524262;2140.5718856228737;2161.729783777028;2121.734783152106;2128.5636444528836;2128.6806978953127;2136.4765632420003;2142.7535833379047;2081.3859911326194;2082.187946700773;2111.5987962287118;2113.17586141405;2114.8519603627597;7322.81692957675;7407.962960185165;7422.109346878356;7444.627768611602;7492.325690687864;4520.909581808345;4659.044895796387;4660.37084993525;4665.183592871268;4666.2067448533835;2798.6921438641734;2915.839628820414;2923.956140657892;2946.0278108224447;3003.3242520432946;1080.4327727942814;1116.1573012375686;1152;1154.261272785418;1163.4868683643374;9936.652217172365;10004.249681273934;10004.54979525923;10012.799744005151;10019.599216031424;3915.256101340736;4049.6152865477734;4143.3370660694745;4170.854020109282;4204;8525.104863989316;8584.914150858536;8589.355798315151;8596;8600.397972142015;4570.794314255866;4628.514006029386;4706.294055891643;4708.623310135181;4717.080169366941;3682.4660424238336;3698.63013698628;4096.877093687194;4264.211120942636;4277.700560960765;3500.7199424046016;3547.113221694576;3612.566492020922;3615.3492371373404;3621.6740493355196;1198.808190689503;1220.2800347794844;1222.1261797814427;1226.343906010298;1234.4753479771061;780.1145699630846;798.5807451088225;803.3693550562897;810.6635746165393;814.2020819596828;4700.506446823103;4894.853154405374;4926.162552366035;4928.753562321869;5160.225966105114;510.2856001597793;514.5831876180306;514.9407818100966;520.8385400525849;529.380624669138;3177.3804108199142;3179.3323402085357;3182.840857957093;3211.229304966791;3289.983550082234;8630.956845215735;8896.131812319356;9007.369484136048;9024.323175761843;9044.276457883354;8974.775630609287;8979.506127162938;8984.820303594022;8988.820223595621;8990.820183596292;6929.792106236821;6966.268541803139;7017.4035207006955;7042.718291268292;7067;4660.766961651836;4775.283707443911;4790.185668436305;4794;4963.503649635008;8096.271335579826;8156.8368632625825;8163.510189388656;8174.346052315682;8228.794280142805;8174.141715120065;8359.456635318686;8370.16298370158;8374.790630234293;8410.957945210235;5735.311762588516;5923.911141332797;6436.13112229843;6527.379898909596;6546.345365463416;6845.5550389224545;7008.088948436671;7043.683034263573;7204.819879503054;7208.135023797178;6312.147860038836;6388.361163883575;6441.967790161019;6567.835804104936;6568.8357791055605;6379.808605741835;6382.648954307558;6412;6417.839554011187;6445.903311450238;5506.476884695948;5626.74679639409;5628.071368224197;5630.8029218977135;5634.23937768396;4069.328560787437;4077.184563087335;4102.794860256916;4220;4255.1915136124035;4272.87181384559;4281.336392859112;4296.505901821331;4406.272964960874;4468.084042771289 17:09:39 INFO - PROCESS | 19500 | |4;prototype.html;344.3491800497061;345.5128269140512;347.0923534956089;349.1724612667974;350.91371388636935;4828.7585620719;4866.926996095057;4885.145099607568;4887.560119589236;4894.3882014748115;4779.9207344804945;4787.952120478794;4795.280707893818;4797.6161907047435;4807.206810876201;3630.8184590770356;3701.666849983501;3825.2923209206388;3825.598312177223;3897.0841852164717;3856.903577410559;3878.3406820840382;3879.941800872989;3883.708721845873;3903.160820423608;3846.576876543578;3851.7688938663628;3860.40163774614;3876.7867767272787;3889.044326312009;3717.6839968602835;3832.559255685604;3839.9808000959815;3897.369275738865;3900.9609903901023;3514.418511669733;3517.4196257617464;3520.1023738946406;3529.1530032792202;3570.232400033979;4738.597219236369;4744.312074749177;4751.239801631757;4754.738489383082;4778.730103200986;4223.66210703143;4391;4395.110102655856;4414.3157810539415;4447.177272204653;5054.797808087672;5066.265391518246;5075.873103172414;5082.771275292584;5107.897842043139;3823.8661646842506;3825.8469661213517;3850.210706805098;3866.1881004988986;3880.417937309426;5072.239164125337;5318.25544423781;5380.461953804627;5389.78729785799;5397.731533089721;3648.5986541480415;3674.3386190485435;3682.779033258013;3693.3592481375945;3701.555813302421;3715.331240376758;3727.310447567209;3735.701143908481;3743.1952130292116;3755.3803622401997;4233.962679143629;4234.882558720639;4243.745375277432;4276.914461710749;4292.527821939584;4905.411350637946;5072.721000344943;5135.460776618401;5148.330717006766;5161.406438259573;1377.5454100146928;1379.3034517568565;1389.8818600418876;1390.930453477322;1405.1779708870424;945.9310978594144;986.5018165826211;994.7913285358325;995.1789773436943;998.4308943902051;4218.8312467500955;4321.783910804447;4323.827046918151;4345.478542574911;4348.934765978513;5132.538071573576;5151.562117220078;5202.505761952609;5242.7378631068295;5254.868628284324;4386.885311428921;4397.516273209944;4397.868063958086;4401.170981246272;4403.779811009436;2623.5933430318332;2676.474409586517;2715.9815069348992;2717.904873329424;2723.850188239666;4719.716816990992;4740.715557066587;4744.715317080986;4746.786394612251;4772.236442169236;5000.898006425729;5221;5223.503767142115;5232.502912223333;5241.397239317527;4667.965822215519;4744.383230180124;4745.217039188496;4750.857474275777;4764.33299338086;5104.387473503203;5115.028144652504;5116.74416279192;5141.845744627667;5148.150555158357;4877.341558889504;5018.920931999638;5022.673526220784;5058.570021548062;5098.490150984871;4698.2952557116705;4710.199266124699;4710.835120770894;4738.455077665974;4764.857054288377;3736;3736.869209577652;3742.9625703743704;3777.981110094432;3778.8299526520823;5117.799346281135;5134.460881607378;5143.8714032148;5148.700834802981;5164.251183578435;4723.504032076583;4945.653804233669;4994.850154495371;5009.5491405773655;5024.673396229379;5011.241568823382;5013.423456302572;5033.974830125826;5039.6220283479615;5057.494250574913;4907.55831975124;4907.895723462192;4923.97538012322;4939.456659767495;4993.875153121201;4837.56461918415;4907.435644900739;5013.899722005613;5125.821525851424;5161.380634323755;4869.732164730832;4995.475475075064;5003;5008.373953255843;5017.749112544431 17:09:39 INFO - PROCESS | 19500 | |5;yui.html;680.4012469027256;691.4261163234515;730.361608383281;736.0688728758122;744.374725230806;11210.887891121087;12314.568990085349;12597.685057873558;12635.777876641467;12659;12998;13057.934710326435;13172.1438106523;13219.801702974462;13242.271675057867;655.5869802024718;672.1598002496879;799.8520273749375;815.9102498725135;815.963281652327;792.5126047480816;816.7958010497375;818.1368656067846;819.7704642700023;824.9505029698207;857.5712143928037;861.4701958295666;864.3128712673408;864.5461132905243;865.9653613855438;693.1128155960379;709.2588245283691;856.4005196362509;856.751542052804;857.0272740439584;610.6671863834221;625.6496362037274;628.6950828848005;631.5105793010454;632.3486808587163;12801.231926084465;12804.80792788109;12807.743845123045;12808.423620937081;12843.100982931199;11534.192606517547;11971.162018658697;12181.573644922473;12252.264864108181;12349.814752778715;10223;10721.517531711092;10789.460526973699;10825.24223304369;11416.657500275005;3267.1668724475344;3329.6503867093847;3337.916552086193;3344.2308269098244;3345.280764635591;61872;62533;62695.68652156756;63260;64118;10574.206934479791;10657;10703.83944240837;10744.785104297871;10845.13238940883;20329.695054574193;20432.591348172955;20478.078486468145;20480.487987800127;20490.795092049186;6734.494912881553;6862.3137686230975;6962.895556566656;7130.714771409085;7152.892706609405;3431.2965842002827;3450.6894379505966;3491.633378495272;3500.754947153675;3535.8585656573964;2851.073401144636;2926;2943.2641839540115;2945.248961514843;2957;489.95835353994596;493.77286448234213;504.87630530520255;506.87074795926804;507.47983317100267;449.274421808783;472.2066927561729;479.26432925459255;525.2724975908394;530.8327876718821;7555.811104722426;7713.922860771433;7766.184550622216;8239.382046346547;8484.490930544187;467.2384014057133;470.32273526122276;472.88887111528766;537.57531550075;602.2892986276148;3746.5316835395574;4243.384709217208;4244.691637987446;4245.596668316505;4613.515580864028;47126.5287347129;51169;53194.468055319725;53371.19943200855;55844.603884903205;43036.784816076346;48286.5171348282;53106.203406948924;53301;53570.46429535655;49110.508894911305;50312;51158;51335.74332128315;52369.738151309;7998.640061197144;8373.790655233668;8415.24262816325;8477.830443391222;8538.78653033679;31075.844620777654;32886.01341959745;33321.16697082592;34950.47574286337;36669.44995825118;43797.124057518035;44658.106837863714;44927;45326.42766798862;45334.31998519959;10520.789584208425;10964.945175274073;10965.780684386427;11214.439278035903;11391.145664075393;12815.551455699006;12822.358882056047;12889.8066529004;12923.870761292266;12949.676258093623;11774.587889423829;11806.232594881305;11850.76298474043;12381.306557580265;12517.499300027897;11338.773224535627;11584.710382240512;11696.824547631622;11971.281723096643;11984.76030479403;11528.654140375802;11550;11609.5356185755;11800.055995520166;11879.881201188224;9846.704598862045;9861.309708320348;10013.499325033865;10276.743081423025;10362.430066346122;9191.310651701282;9570.952145239507;9631.470269135265;9687.370320929118;9696.272779541421 17:09:39 INFO - PROCESS | 19500 | __end_tp_report 17:09:39 INFO - PROCESS | 19500 | __start_cc_report 17:09:39 INFO - PROCESS | 19500 | _x_x_mozilla_cycle_collect,1358 17:09:39 INFO - PROCESS | 19500 | __end_cc_report 17:09:39 INFO - PROCESS | 19500 | __startTimestamp1482973779089__endTimestamp 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | ------- Summary: start ------- 17:09:39 INFO - PROCESS | 19500 | Number of tests: 6 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#0] dojo.html Cycles:165 Average:4689.07 Median:4809.23 stddev:500.45 (10.4%) stddev-sans-first:472.49 17:09:39 INFO - PROCESS | 19500 | Values: 2531.4 2544.6 2563.9 2573.1 2574.8 4626.9 4675.1 4686.4 4715.4 4726.2 4746.9 4761.4 4784.0 4809.2 4848.5 4337.7 4356.8 4408.8 4452.6 4455.9 4491.3 4514.5 4521.6 4522.3 4530.5 4313.6 4443.8 4467.6 4512.4 4516.5 4327.6 4396.4 4403.5 4405.8 4428.8 4387.8 4399.8 4402.2 4433.2 4470.3 4751.0 4781.1 4801.7 4811.9 4847.0 4907.8 4941.0 4944.2 4981.8 5106.8 5071.5 5091.4 5101.8 5112.0 5136.3 4743.1 4745.0 4754.3 4756.2 4801.6 5083.6 5186.5 5187.9 5193.4 5232.5 4990.2 4991.0 4998.8 5051.7 5101.2 4931.1 4966.0 5122.3 5124.2 5125.6 4946.8 4961.4 4995.9 5001.1 5066.2 4945.1 5007.6 5013.0 5016.3 5074.7 4218.9 4245.1 4291.0 4297.8 4316.7 3722.9 3730.8 3732.9 3758.5 3758.8 4317.4 4320.6 4323.6 4344.2 4367.3 4975.9 5010.1 5012.3 5070.2 5142.0 4259.3 4279.5 4280.2 4288.5 4292.0 4559.9 4563.0 4564.2 4575.9 4579.9 4662.9 4701.7 4711.7 4718.4 4720.1 5063.8 5076.0 5076.9 5083.4 5094.5 4681.0 4687.4 4700.1 4714.7 4800.1 5013.8 5019.9 5021.3 5026.6 5067.4 5107.2 5108.5 5119.6 5120.1 5122.9 4814.3 4843.4 4845.5 4893.0 4905.4 4945.3 4992.9 5014.6 5021.4 5076.7 4955.3 4958.2 4971.6 4982.8 5019.5 4931.8 5081.5 5086.1 5086.6 5094.6 4778.3 4830.7 4847.3 4857.2 4871.9 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#1] ext.html Cycles:180 Average:38795.17 Median:19438.90 stddev:61389.40 (315.8%) stddev-sans-first:61494.33 17:09:39 INFO - PROCESS | 19500 | Values: 516.1 538.5 541.0 548.7 562.9 28470.3 30743.4 30804.0 37533.6 39157.0 29223.7 29503.6 29506.3 29541.3 29595.9 7593.1 7767.1 7767.6 7769.9 7773.2 7479.0 7559.0 7656.0 7669.0 9739.9 9638.9 9922.4 9931.0 9939.6 9942.4 9885.9 9918.8 9920.7 9930.4 9963.6 9376.9 9403.6 9434.1 9465.7 9483.4 36576.6 36945.0 36961.6 36985.0 37009.6 3656.6 3711.4 3717.5 3718.1 3770.8 27856.6 27968.4 28033.5 28261.7 29050.0 3360.5 3420.1 3474.5 3479.5 3532.8 235118.0 235932.0 237074.0 237638.0 238246.0 49136.8 49529.0 49561.8 49639.5 49657.0 52016.5 52550.7 52651.0 52680.0 52692.0 11867.1 12163.3 12186.4 12257.1 12339.6 8248.9 8314.4 8317.8 8319.1 8322.1 13432.0 13882.5 14159.0 14437.6 14649.8 5624.6 5652.5 5691.3 5721.5 5756.6 709.6 712.4 723.8 728.8 731.6 26648.0 27667.9 27691.9 27979.0 28323.7 778.6 779.2 781.2 787.2 828.3 14022.7 14779.1 14782.9 14898.7 14906.4 142000.0 142669.0 142689.0 142949.7 142957.0 48446.8 48661.5 48750.8 48755.0 48848.0 1141.0 1183.7 1185.4 1194.2 1199.3 25953.2 26594.9 26652.6 27468.9 27634.2 40813.8 41144.2 41472.0 41477.8 41563.6 295930.0 296153.0 297612.8 298234.0 300311.0 30398.8 34193.3 34544.8 34586.5 34646.3 35345.0 40323.0 43305.0 43592.7 44249.1 34697.3 34847.5 35044.0 35674.3 35700.0 34806.5 35035.5 35078.1 35184.8 35187.8 34003.5 34231.5 34275.0 34636.7 34883.0 28425.4 31228.2 31240.6 31240.9 31323.0 24003.0 24035.5 24341.6 24514.4 24728.8 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#2] jquery.html Cycles:180 Average:22134.39 Median:29166.12 stddev:55601.13 (190.6%) stddev-sans-first:55733.09 17:09:39 INFO - PROCESS | 19500 | Values: 368.4 378.7 379.8 385.3 398.5 15477.1 15945.9 16584.9 16620.8 16672.2 13726.4 14428.8 16514.0 16762.1 16868.2 4553.3 4800.0 4806.4 4807.4 4812.2 3700.8 3928.6 4715.7 5186.4 5477.5 5567.5 5574.7 5577.3 5577.6 5580.6 5314.4 5413.7 5421.5 5448.0 5587.3 5261.7 5262.4 5268.4 5278.3 5281.1 13518.2 14902.6 19567.4 19713.8 19736.5 2165.1 2211.1 2216.6 2236.4 2290.1 9110.5 9144.4 9155.6 9537.1 9789.4 1707.5 1717.3 1736.9 1737.1 1743.2 59673.7 65502.7 66658.0 68159.3 68771.0 9584.3 9740.4 9768.6 10146.7 10345.8 13823.2 13840.9 13845.1 13859.4 13869.9 6288.7 6471.8 6602.3 6634.5 6639.1 5681.7 5734.1 5851.9 5860.9 5861.2 4309.3 4387.6 4398.8 4456.2 4524.9 1733.5 1808.2 1817.5 1821.7 1882.9 723.6 762.5 768.8 770.1 792.6 15846.0 15852.0 16053.2 16500.0 16979.3 738.9 741.9 750.8 752.8 767.9 8753.6 9166.7 9228.1 9293.8 9368.8 330762.0 335956.0 336467.0 336647.0 336943.0 70252.3 71393.0 71562.0 74061.3 74103.6 920.9 927.8 946.1 956.5 961.8 15017.6 15273.2 15686.1 15718.1 15966.6 33187.0 33308.7 33957.7 37623.2 38274.6 42053.2 46348.8 47369.3 53051.1 54363.5 7493.4 7580.7 7783.5 7819.1 7910.4 10105.8 10608.2 11459.7 11636.8 11961.6 9357.9 9377.1 9525.5 9760.8 9956.0 9450.2 9831.7 9934.5 10069.9 10825.7 9345.4 9546.4 9549.1 9577.5 9584.5 7599.3 7623.0 7786.3 7859.0 8041.1 7899.3 9466.8 9559.8 9681.2 9945.5 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#3] mootools.html Cycles:180 Average:4838.49 Median:4746.18 stddev:2665.36 (56.2%) stddev-sans-first:2659.33 17:09:39 INFO - PROCESS | 19500 | Values: 1267.2 1282.1 1290.0 1297.4 1297.5 6926.5 7341.0 7347.4 7349.8 7350.7 6553.8 6760.1 7056.2 7225.2 7230.8 2085.3 2117.2 2119.8 2120.6 2121.4 2157.5 2188.0 2197.2 2203.8 2208.4 1728.9 1786.4 2072.8 2140.6 2161.7 2121.7 2128.6 2128.7 2136.5 2142.8 2081.4 2082.2 2111.6 2113.2 2114.9 7322.8 7408.0 7422.1 7444.6 7492.3 4520.9 4659.0 4660.4 4665.2 4666.2 2798.7 2915.8 2924.0 2946.0 3003.3 1080.4 1116.2 1152.0 1154.3 1163.5 9936.7 10004.2 10004.5 10012.8 10019.6 3915.3 4049.6 4143.3 4170.9 4204.0 8525.1 8584.9 8589.4 8596.0 8600.4 4570.8 4628.5 4706.3 4708.6 4717.1 3682.5 3698.6 4096.9 4264.2 4277.7 3500.7 3547.1 3612.6 3615.3 3621.7 1198.8 1220.3 1222.1 1226.3 1234.5 780.1 798.6 803.4 810.7 814.2 4700.5 4894.9 4926.2 4928.8 5160.2 510.3 514.6 514.9 520.8 529.4 3177.4 3179.3 3182.8 3211.2 3290.0 8631.0 8896.1 9007.4 9024.3 9044.3 8974.8 8979.5 8984.8 8988.8 8990.8 6929.8 6966.3 7017.4 7042.7 7067.0 4660.8 4775.3 4790.2 4794.0 4963.5 8096.3 8156.8 8163.5 8174.3 8228.8 8174.1 8359.5 8370.2 8374.8 8411.0 5735.3 5923.9 6436.1 6527.4 6546.3 6845.6 7008.1 7043.7 7204.8 7208.1 6312.1 6388.4 6442.0 6567.8 6568.8 6379.8 6382.6 6412.0 6417.8 6445.9 5506.5 5626.7 5628.1 5630.8 5634.2 4069.3 4077.2 4102.8 4220.0 4255.2 4272.9 4281.3 4296.5 4406.3 4468.1 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#4] prototype.html Cycles:180 Average:4203.17 Median:4744.55 stddev:1175.85 (24.8%) stddev-sans-first:1142.93 17:09:39 INFO - PROCESS | 19500 | Values: 344.3 345.5 347.1 349.2 350.9 4828.8 4866.9 4885.1 4887.6 4894.4 4779.9 4788.0 4795.3 4797.6 4807.2 3630.8 3701.7 3825.3 3825.6 3897.1 3856.9 3878.3 3879.9 3883.7 3903.2 3846.6 3851.8 3860.4 3876.8 3889.0 3717.7 3832.6 3840.0 3897.4 3901.0 3514.4 3517.4 3520.1 3529.2 3570.2 4738.6 4744.3 4751.2 4754.7 4778.7 4223.7 4391.0 4395.1 4414.3 4447.2 5054.8 5066.3 5075.9 5082.8 5107.9 3823.9 3825.8 3850.2 3866.2 3880.4 5072.2 5318.3 5380.5 5389.8 5397.7 3648.6 3674.3 3682.8 3693.4 3701.6 3715.3 3727.3 3735.7 3743.2 3755.4 4234.0 4234.9 4243.7 4276.9 4292.5 4905.4 5072.7 5135.5 5148.3 5161.4 1377.5 1379.3 1389.9 1390.9 1405.2 945.9 986.5 994.8 995.2 998.4 4218.8 4321.8 4323.8 4345.5 4348.9 5132.5 5151.6 5202.5 5242.7 5254.9 4386.9 4397.5 4397.9 4401.2 4403.8 2623.6 2676.5 2716.0 2717.9 2723.9 4719.7 4740.7 4744.7 4746.8 4772.2 5000.9 5221.0 5223.5 5232.5 5241.4 4668.0 4744.4 4745.2 4750.9 4764.3 5104.4 5115.0 5116.7 5141.8 5148.2 4877.3 5018.9 5022.7 5058.6 5098.5 4698.3 4710.2 4710.8 4738.5 4764.9 3736.0 3736.9 3743.0 3778.0 3778.8 5117.8 5134.5 5143.9 5148.7 5164.3 4723.5 4945.7 4994.9 5009.5 5024.7 5011.2 5013.4 5034.0 5039.6 5057.5 4907.6 4907.9 4924.0 4939.5 4993.9 4837.6 4907.4 5013.9 5125.8 5161.4 4869.7 4995.5 5003.0 5008.4 5017.7 17:09:39 INFO - PROCESS | 19500 | 17:09:39 INFO - PROCESS | 19500 | [#5] yui.html Cycles:180 Average:14145.90 Median:22688.14 stddev:16863.77 (74.3%) stddev-sans-first:16880.76 17:09:39 INFO - PROCESS | 19500 | Values: 680.4 691.4 730.4 736.1 744.4 11210.9 12314.6 12597.7 12635.8 12659.0 12998.0 13057.9 13172.1 13219.8 13242.3 655.6 672.2 799.9 815.9 816.0 792.5 816.8 818.1 819.8 825.0 857.6 861.5 864.3 864.5 866.0 693.1 709.3 856.4 856.8 857.0 610.7 625.6 628.7 631.5 632.3 12801.2 12804.8 12807.7 12808.4 12843.1 11534.2 11971.2 12181.6 12252.3 12349.8 10223.0 10721.5 10789.5 10825.2 11416.7 3267.2 3329.7 3337.9 3344.2 3345.3 61872.0 62533.0 62695.7 63260.0 64118.0 10574.2 10657.0 10703.8 10744.8 10845.1 20329.7 20432.6 20478.1 20480.5 20490.8 6734.5 6862.3 6962.9 7130.7 7152.9 3431.3 3450.7 3491.6 3500.8 3535.9 2851.1 2926.0 2943.3 2945.2 2957.0 490.0 493.8 504.9 506.9 507.5 449.3 472.2 479.3 525.3 530.8 7555.8 7713.9 7766.2 8239.4 8484.5 467.2 470.3 472.9 537.6 602.3 3746.5 4243.4 4244.7 4245.6 4613.5 47126.5 51169.0 53194.5 53371.2 55844.6 43036.8 48286.5 53106.2 53301.0 53570.5 49110.5 50312.0 51158.0 51335.7 52369.7 7998.6 8373.8 8415.2 8477.8 8538.8 31075.8 32886.0 33321.2 34950.5 36669.4 43797.1 44658.1 44927.0 45326.4 45334.3 10520.8 10964.9 10965.8 11214.4 11391.1 12815.6 12822.4 12889.8 12923.9 12949.7 11774.6 11806.2 11850.8 12381.3 12517.5 11338.8 11584.7 11696.8 11971.3 11984.8 11528.7 11550.0 11609.5 11800.1 11879.9 9846.7 9861.3 10013.5 10276.7 10362.4 9191.3 9571.0 9631.5 9687.4 9696.3 17:09:39 INFO - PROCESS | 19500 | -------- Summary: end -------- 17:09:39 INFO - PROCESS | 19500 | 17:09:40 INFO - TEST-INFO | 19500: exit 0 17:09:40 INFO - TEST-OK | dromaeo_css | took 1121824ms 17:09:40 INFO - TEST-START | kraken 17:09:40 INFO - operating with platform_type : linux_ 17:09:40 INFO - Initialising browser for kraken test... 17:09:40 INFO - TEST-INFO | started process 19798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4qjho7/profile http://localhost:50891/getInfo.html) 17:09:40 INFO - PROCESS | 19798 | 1482973780507 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:09:44 INFO - PROCESS | 19798 | __metrics Screen width/height:1600/1200 17:09:44 INFO - PROCESS | 19798 | colorDepth:24 17:09:44 INFO - PROCESS | 19798 | Browser inner width/height: 1024/697 17:09:44 INFO - PROCESS | 19798 | __metrics 17:09:44 INFO - PROCESS | 19798 | 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" 17:09:44 INFO - PROCESS | 19798 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:09:45 INFO - PROCESS | 19798 | console.error: PushService: 17:09:45 INFO - PROCESS | 19798 | stateChangeProcessEnqueue: Error transitioning state 17:09:45 INFO - PROCESS | 19798 | UnknownError 17:09:46 INFO - TEST-INFO | 19798: exit 0 17:09:46 INFO - Browser initialized. 17:09:46 INFO - Running cycle 1/1 for kraken test... 17:09:46 INFO - TEST-INFO | started process 19886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4qjho7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:09:58 INFO - PROCESS | 19886 | RSS: Main: 151064576 17:09:58 INFO - PROCESS | 19886 | 17:10:01 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50891/tests/kraken/driver.html?testName=audio-beat-detection) 17:10:02 INFO - PROCESS | 19886 | RSS: Main: 205062144 17:10:02 INFO - PROCESS | 19886 | 17:10:04 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50891/tests/kraken/driver.html?testName=audio-dft) 17:10:05 INFO - PROCESS | 19886 | RSS: Main: 184430592 17:10:05 INFO - PROCESS | 19886 | 17:10:08 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50891/tests/kraken/driver.html?testName=audio-fft) 17:10:09 INFO - PROCESS | 19886 | RSS: Main: 266891264 17:10:09 INFO - PROCESS | 19886 | 17:10:11 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50891/tests/kraken/driver.html?testName=audio-oscillator) 17:10:11 INFO - PROCESS | 19886 | RSS: Main: 178487296 17:10:11 INFO - PROCESS | 19886 | 17:10:14 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:10:14 INFO - PROCESS | 19886 | RSS: Main: 182308864 17:10:14 INFO - PROCESS | 19886 | 17:10:17 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50891/tests/kraken/driver.html?testName=imaging-darkroom) 17:10:18 INFO - PROCESS | 19886 | RSS: Main: 221712384 17:10:18 INFO - PROCESS | 19886 | 17:10:22 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50891/tests/kraken/driver.html?testName=imaging-desaturate) 17:10:22 INFO - PROCESS | 19886 | RSS: Main: 215076864 17:10:22 INFO - PROCESS | 19886 | 17:10:25 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50891/tests/kraken/driver.html?testName=json-parse-financial) 17:10:26 INFO - PROCESS | 19886 | RSS: Main: 217083904 17:10:26 INFO - PROCESS | 19886 | 17:10:27 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:10:28 INFO - PROCESS | 19886 | RSS: Main: 180150272 17:10:28 INFO - PROCESS | 19886 | 17:10:30 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:10:30 INFO - PROCESS | 19886 | RSS: Main: 188035072 17:10:30 INFO - PROCESS | 19886 | 17:10:33 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:10:33 INFO - PROCESS | 19886 | RSS: Main: 196145152 17:10:33 INFO - PROCESS | 19886 | 17:10:36 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:10:37 INFO - PROCESS | 19886 | RSS: Main: 206098432 17:10:37 INFO - PROCESS | 19886 | 17:10:40 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:10:41 INFO - PROCESS | 19886 | RSS: Main: 201760768 17:10:41 INFO - PROCESS | 19886 | 17:10:42 INFO - PROCESS | 19886 | Cycle 1(1): loaded http://localhost:50891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50891/tests/kraken/driver.html?testName=ai-astar) 17:10:43 INFO - PROCESS | 19886 | RSS: Main: 253755392 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | __start_tp_report 17:10:43 INFO - PROCESS | 19886 | _x_x_mozilla_page_load 17:10:43 INFO - PROCESS | 19886 | _x_x_mozilla_page_load_details 17:10:43 INFO - PROCESS | 19886 | |i|pagename|runs| 17:10:43 INFO - PROCESS | 19886 | |0;ai-astar;108;114;117;114;117;114;117;116;115;115 17:10:43 INFO - PROCESS | 19886 | |1;audio-beat-detection;152;132;127;169;128;128;129;132;147;125 17:10:43 INFO - PROCESS | 19886 | |2;audio-dft;161;162;161;162;161;163;162;160;161;162 17:10:43 INFO - PROCESS | 19886 | |3;audio-fft;75;77;81;116;77;77;79;78;100;76 17:10:43 INFO - PROCESS | 19886 | |4;audio-oscillator;86;85;114;84;85;86;110;86;85;118 17:10:43 INFO - PROCESS | 19886 | |5;imaging-gaussian-blur;117;117;118;118;117;116;117;117;118;118 17:10:43 INFO - PROCESS | 19886 | |6;imaging-darkroom;149;148;149;148;149;150;148;149;148;150 17:10:43 INFO - PROCESS | 19886 | |7;imaging-desaturate;96;95;95;96;96;96;96;95;95;95 17:10:43 INFO - PROCESS | 19886 | |8;json-parse-financial;63;62;67;63;64;63;63;63;64;65 17:10:43 INFO - PROCESS | 19886 | |9;json-stringify-tinderbox;68;66;67;67;68;66;67;66;67;68 17:10:43 INFO - PROCESS | 19886 | |10;stanford-crypto-aes;86;85;85;85;94;84;85;84;94;85 17:10:43 INFO - PROCESS | 19886 | |11;stanford-crypto-ccm;134;134;133;135;143;137;132;131;183;144 17:10:43 INFO - PROCESS | 19886 | |12;stanford-crypto-pbkdf2;182;183;180;182;176;187;180;185;182;184 17:10:43 INFO - PROCESS | 19886 | |13;stanford-crypto-sha256-iterative;68;72;70;70;69;70;69;68;68;70 17:10:43 INFO - PROCESS | 19886 | __end_tp_report 17:10:43 INFO - PROCESS | 19886 | __start_cc_report 17:10:43 INFO - PROCESS | 19886 | _x_x_mozilla_cycle_collect,1855 17:10:43 INFO - PROCESS | 19886 | __end_cc_report 17:10:43 INFO - PROCESS | 19886 | __startTimestamp1482973843087__endTimestamp 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | ------- Summary: start ------- 17:10:43 INFO - PROCESS | 19886 | Number of tests: 14 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.50 stddev:2.67 (2.3%) stddev-sans-first:1.33 17:10:43 INFO - PROCESS | 19886 | Values: 108.0 114.0 117.0 114.0 117.0 114.0 117.0 116.0 115.0 115.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:132.00 stddev:14.41 (10.9%) stddev-sans-first:14.21 17:10:43 INFO - PROCESS | 19886 | Values: 152.0 132.0 127.0 169.0 128.0 128.0 129.0 132.0 147.0 125.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 17:10:43 INFO - PROCESS | 19886 | Values: 161.0 162.0 161.0 162.0 161.0 163.0 162.0 160.0 161.0 162.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#3] audio-fft Cycles:10 Average:83.60 Median:77.00 stddev:13.50 (17.5%) stddev-sans-first:13.96 17:10:43 INFO - PROCESS | 19886 | Values: 75.0 77.0 81.0 116.0 77.0 77.0 79.0 78.0 100.0 76.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#4] audio-oscillator Cycles:10 Average:93.90 Median:85.00 stddev:14.01 (16.5%) stddev-sans-first:14.57 17:10:43 INFO - PROCESS | 19886 | Values: 86.0 85.0 114.0 84.0 85.0 86.0 110.0 86.0 85.0 118.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 17:10:43 INFO - PROCESS | 19886 | Values: 117.0 117.0 118.0 118.0 117.0 116.0 117.0 117.0 118.0 118.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 17:10:43 INFO - PROCESS | 19886 | Values: 149.0 148.0 149.0 148.0 149.0 150.0 148.0 149.0 148.0 150.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:10:43 INFO - PROCESS | 19886 | Values: 96.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:63.50 stddev:1.42 (2.2%) stddev-sans-first:1.48 17:10:43 INFO - PROCESS | 19886 | Values: 63.0 62.0 67.0 63.0 64.0 63.0 63.0 63.0 64.0 65.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 17:10:43 INFO - PROCESS | 19886 | Values: 68.0 66.0 67.0 67.0 68.0 66.0 67.0 66.0 67.0 68.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:3.89 (4.6%) stddev-sans-first:4.12 17:10:43 INFO - PROCESS | 19886 | Values: 86.0 85.0 85.0 85.0 94.0 84.0 85.0 84.0 94.0 85.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:136.00 stddev:15.53 (11.4%) stddev-sans-first:16.29 17:10:43 INFO - PROCESS | 19886 | Values: 134.0 134.0 133.0 135.0 143.0 137.0 132.0 131.0 183.0 144.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:182.50 stddev:3.03 (1.7%) stddev-sans-first:3.22 17:10:43 INFO - PROCESS | 19886 | Values: 182.0 183.0 180.0 182.0 176.0 187.0 180.0 185.0 182.0 184.0 17:10:43 INFO - PROCESS | 19886 | 17:10:43 INFO - PROCESS | 19886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.26 (1.8%) stddev-sans-first:1.24 17:10:43 INFO - PROCESS | 19886 | Values: 68.0 72.0 70.0 70.0 69.0 70.0 69.0 68.0 68.0 70.0 17:10:43 INFO - PROCESS | 19886 | -------- Summary: end -------- 17:10:43 INFO - PROCESS | 19886 | 17:10:44 INFO - TEST-INFO | 19886: exit 0 17:10:44 INFO - TEST-OK | kraken | took 64245ms 17:10:44 INFO - SUITE-END | took 1186s 17:10:44 INFO - Completed test suite (00:19:46) 17:10:44 INFO - javascript benchmark 17:10:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2531.405119796848, 2544.592865141578, 2563.9359016024605, 2573.0994152046787, 2574.768270855624, 4626.930596041054, 4675.1351000065015, 4686.390769200004, 4715.41057367829, 4726.243800991838, 4746.881327966794, 4761.40482439695, 4784.043191361724, 4809.230523116302, 4848.515148485149, 4337.718048326865, 4356.7821608919585, 4408.8016039278145, 4452.6215271701785, 4455.866324010285, 4491.348754430605, 4514.480834704018, 4521.5930566249035, 4522.276435770261, 4530.4789949155775, 4313.61177494027, 4443.844465443726, 4467.597916187542, 4512.4133862597655, 4516.458025036984, 4327.567243275647, 4396.428464299653, 4403.493598236212, 4405.779711014468, 4428.822847086113, 4387.802548885308, 4399.78001099947, 4402.185595664812, 4433.22418576751, 4470.329450582406, 4750.976245118753, 4781.115493633689, 4801.7118972861745, 4811.903761924742, 4846.975765121152, 4907.7546122694075, 4941.036497882914, 4944.184209605411, 4981.775820088104, 5106.846794596168, 5071.46749591295, 5091.389033315989, 5101.795928162869, 5112.028714544224, 5136.280920671109, 4743.051389722069, 4745.00354925466, 4754.263089221176, 4756.215224487921, 4801.639877009238, 5083.61872859537, 5186.481351864783, 5187.870303242374, 5193.37679478465, 5232.502912223333, 4990.226514890198, 4990.950090499121, 4998.825041123543, 5051.722155281494, 5101.234814777813, 4931.112399768076, 4965.975170124127, 5122.282880396748, 5124.231365295161, 5125.589952803842, 4946.826861059918, 4961.3798275215595, 4995.900081998339, 5001.124803159432, 5066.214736715815, 4945.060438516671, 5007.624428167902, 5013.047520971004, 5016.347874776256, 5074.695518268915, 4218.936715949263, 4245.12974840157, 4290.957090429119, 4297.78511074445, 4316.6546676265825, 3722.8883133506038, 3730.776153430803, 3732.925341493155, 3758.4550240215563, 3758.7932663703214, 4317.395564620955, 4320.611144996966, 4323.589259020388, 4344.239758042393, 4367.301231802896, 4975.900481990412, 5010.098182327143, 5012.323336349619, 5070.163423035232, 5142.048720986555, 4259.2759230930815, 4279.507856596531, 4280.208161490134, 4288.50682171548, 4292.012837047497, 4559.908801823945, 4563.041761230113, 4564.2240819060835, 4575.9084818303445, 4579.862604121881, 4662.930056049162, 4701.717896926195, 4711.740854253049, 4718.386609740712, 4720.103180395714, 5063.772130254074, 5075.974620126875, 5076.898462030665, 5083.364579427572, 5094.5160209780015, 4681.0, 4687.39063921688, 4700.0834837207785, 4714.693544919569, 4800.06398752254, 5013.849584512471, 5019.924701129413, 5021.297018417352, 5026.648134630541, 5067.391912970467, 5107.233914912793, 5108.514691104339, 5119.5648369887485, 5120.078385890575, 5122.923156152586, 4814.325994360863, 4843.370361852936, 4845.53967373099, 4893.0, 4905.3623029006, 4945.282933974626, 4992.8751781205765, 5014.5737612303365, 5021.447640759587, 5076.695398276115, 4955.281484184845, 4958.181899986462, 4971.577415919543, 4982.825601103944, 5019.498050194951, 4931.753412329441, 5081.466446023114, 5086.109930762176, 5086.593072554113, 5094.5669618082875, 4778.33103365536, 4830.686005409637, 4847.2729090636685, 4857.1742803724155, 4871.9269210962575], "value": 4655.66310264184, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.0684963640629, 538.5207165622595, 540.953255450703, 548.6817645765459, 562.8817948230871, 28470.288242793915, 30743.385132297368, 30803.99999999997, 37533.624663753355, 39157.0, 29223.707762922364, 29503.557446638264, 29506.262343441424, 29541.261468463246, 29595.85202073992, 7593.126790419104, 7767.067951845786, 7767.6116194190345, 7769.883451748228, 7773.2226777322385, 7478.962605186994, 7558.962205188994, 7655.961720191392, 7669.041369828771, 9739.853902191473, 9638.855417168714, 9922.355046921963, 9931.0, 9939.55272012761, 9942.353747006458, 9885.901140988606, 9918.80162396748, 9920.652777152765, 9930.404175749478, 9963.551640176172, 9376.906230937671, 9403.62385504579, 9434.05659434058, 9465.66870159541, 9483.383580067273, 36576.63423365759, 36945.0, 36961.630383696356, 36985.0, 37009.62990370089, 3656.554982903495, 3711.4247291669562, 3717.5353080864893, 3718.1262403335195, 3770.811459427018, 27856.582151267543, 27968.440631187263, 28033.47904575771, 28261.717382826115, 29050.0, 3360.462326027848, 3420.1441796100285, 3474.5483087198754, 3479.4954731564035, 3532.8056956867363, 235118.0, 235932.0, 237073.99999999828, 237638.0, 238246.0, 49136.75431622891, 49529.0, 49561.75219123881, 49639.50360496349, 49657.0, 52016.47983520192, 52550.737246313525, 52651.0, 52680.0, 52692.0, 11867.050635949103, 12163.2702037878, 12186.390680465942, 12257.14200005991, 12339.568115115928, 8248.917510824815, 8314.376421768271, 8317.792055198548, 8319.126491718403, 8322.126176751475, 13432.0, 13882.51411200603, 14159.0, 14437.639059023608, 14649.780253296209, 5624.55003599711, 5652.541144602029, 5691.288588926384, 5721.456461636138, 5756.597038207286, 709.5884387055495, 712.4229374206806, 723.8190452386904, 728.7558667846225, 731.5829976913109, 26648.0, 27667.861660691568, 27691.861540692167, 27979.0, 28323.716762832108, 778.5717855179629, 779.1974266505508, 781.199270747477, 787.1616728184465, 828.2918105020121, 14022.71954560903, 14779.136511105566, 14782.869421817957, 14898.702025959421, 14906.403743850342, 142000.0, 142669.0, 142689.0, 142949.70502949622, 142957.0, 48446.75776621094, 48661.513384866405, 48750.756246218545, 48755.0, 48848.0, 1140.9771804563861, 1183.6804062902968, 1185.4013723069795, 1194.2237545595258, 1199.2804317409484, 25953.22140335793, 26594.86702566475, 26652.60021099724, 27468.862655686593, 27634.170974870787, 40813.795931020155, 41144.1771164581, 41472.0, 41477.792611036755, 41563.584364157185, 295930.0, 296153.0, 297612.8222689996, 298234.0, 300311.0, 30398.848005759828, 34193.31613367768, 34544.82727586346, 34586.48120278148, 34646.30707385889, 35345.0, 40323.0, 43305.0, 43592.67906153653, 44249.11501770011, 34697.306053879285, 34847.47728784019, 35044.0, 35674.28651427009, 35700.0, 34806.47790283097, 35035.47446788351, 35078.12304692403, 35184.824075879456, 35187.82406087953, 34003.48994765031, 34231.48652770161, 34275.0, 34636.65363346435, 34883.0, 28425.427335484546, 31228.20844078465, 31240.58011518475, 31240.916741163994, 31323.0, 24003.0, 24035.519289614458, 24341.634875477237, 24514.387140321636, 24728.752712472644], "value": 16419.759777266107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.42709586592855, 378.7159630277293, 379.80440073362223, 385.3295266236749, 398.4680451597118, 15477.148756818371, 15945.920270398661, 16584.91707541462, 16620.83379166208, 16672.1663916804, 13726.382312795924, 14428.783568246485, 16514.0, 16762.07808570528, 16868.156592170373, 4553.317002449634, 4800.0, 4806.399200099988, 4807.375041244633, 4812.23004319309, 3700.7594506357, 3928.6267804558665, 4715.6934799238115, 5186.429492755794, 5477.4796394342475, 5567.526760225385, 5574.749136288857, 5577.274954255941, 5577.637453565526, 5580.637258578179, 5314.362276526803, 5413.675179489204, 5421.512063914267, 5447.972760136174, 5587.3015873015875, 5261.6842989420375, 5262.394824595144, 5268.446813084609, 5278.287431196778, 5281.102212623863, 13518.188908665512, 14902.552923412206, 19567.41297761055, 19713.802861971482, 19736.506587335432, 2165.058199683143, 2211.1155537784853, 2216.6342553478657, 2236.3961730332717, 2290.0954123121273, 9110.5444727764, 9144.40561363516, 9155.633774649006, 9537.093976072261, 9789.412635241908, 1707.5133586927666, 1717.313074770102, 1736.8697347698974, 1737.1053907237783, 1743.1545700335328, 59673.70163149157, 65502.67248663774, 66658.0, 68159.31840681579, 68771.0, 9584.28117891161, 9740.415575065519, 9768.560414781352, 10146.746331341776, 10345.844812327821, 13823.170609763447, 13840.861591384159, 13845.100068495516, 13859.376328065262, 13869.930650346683, 6288.685565721696, 6471.838204044937, 6602.2737498875085, 6634.502412318999, 6639.070530125786, 5681.715914204273, 5734.05388110957, 5851.941480585225, 5860.882782344329, 5861.208736820475, 4309.332053531708, 4387.583179597933, 4398.834308908142, 4456.17560751262, 4524.932126018112, 1733.514615907548, 1808.1953530678722, 1817.4729328494852, 1821.7449557062023, 1882.8776129551536, 723.6309482164135, 762.5158024654273, 768.7616838779996, 770.1182146442292, 792.5640897506348, 15846.0, 15852.0, 16053.197340132947, 16500.0, 16979.320827167023, 738.881778915371, 741.8627553902545, 750.8385697075155, 752.8306131120563, 767.8733009053443, 8753.649854005896, 9166.724998250063, 9228.123328283802, 9293.767655808524, 9368.765780855532, 330762.0, 335956.0, 336467.0, 336647.0, 336943.0, 70252.2974770256, 71393.0, 71562.0, 74061.25938740543, 74103.62948185224, 920.8526635738384, 927.7634203278121, 946.129560804062, 956.5360800011989, 961.8028304197762, 15017.624559385666, 15273.159976201416, 15686.137262450675, 15718.13550254747, 15966.60083497922, 33187.0, 33308.666913330555, 33957.66042339545, 37623.2475350486, 38274.61725382822, 42053.220154271556, 46348.7682561585, 47369.28946065743, 53051.143209987466, 54363.45636543584, 7493.4379921505015, 7580.734674286374, 7783.532988020737, 7819.100803407453, 7910.406719496176, 10105.848412273968, 10608.204384671026, 11459.713507162054, 11636.825447618123, 11961.581344652895, 9357.859632105388, 9377.1091746284, 9525.523723813643, 9760.755981100529, 9956.004399559986, 9450.196733277748, 9831.655892043744, 9934.453605051502, 10069.949650251701, 10825.729356766144, 9345.392549484262, 9546.37948533371, 9549.09283618055, 9577.473238971923, 9584.472853992818, 7599.316061554487, 7622.961885190539, 7786.338161256356, 7858.960705196438, 8041.075276343061, 7899.328557072713, 9466.810663786548, 9559.761005974906, 9681.225501959967, 9945.453000085065], "value": 7863.282271875308, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.207995003123, 1282.089716301426, 1290.045366428843, 1297.37725891572, 1297.5134324628264, 6926.515143939926, 7341.0, 7347.375473084788, 7349.779506614804, 7350.742724004647, 6553.803385898431, 6760.121184246053, 7056.188538318095, 7225.205227424979, 7230.7830765077, 2085.280578200522, 2117.2483768262277, 2119.7668256491775, 2120.586485635302, 2121.448423409917, 2157.514559224178, 2188.0, 2197.1760589778833, 2203.8126759225484, 2208.3706143749037, 1728.8789784715075, 1786.428342930263, 2072.7823578524262, 2140.5718856228737, 2161.729783777028, 2121.734783152106, 2128.5636444528836, 2128.6806978953127, 2136.4765632420003, 2142.7535833379047, 2081.3859911326194, 2082.187946700773, 2111.5987962287118, 2113.17586141405, 2114.8519603627597, 7322.81692957675, 7407.962960185165, 7422.109346878356, 7444.627768611602, 7492.325690687864, 4520.909581808345, 4659.044895796387, 4660.37084993525, 4665.183592871268, 4666.2067448533835, 2798.6921438641734, 2915.839628820414, 2923.956140657892, 2946.0278108224447, 3003.3242520432946, 1080.4327727942814, 1116.1573012375686, 1152.0, 1154.261272785418, 1163.4868683643374, 9936.652217172365, 10004.249681273934, 10004.54979525923, 10012.799744005151, 10019.599216031424, 3915.256101340736, 4049.6152865477734, 4143.3370660694745, 4170.854020109282, 4204.0, 8525.104863989316, 8584.914150858536, 8589.355798315151, 8596.0, 8600.397972142015, 4570.794314255866, 4628.514006029386, 4706.294055891643, 4708.623310135181, 4717.080169366941, 3682.4660424238336, 3698.63013698628, 4096.877093687194, 4264.211120942636, 4277.700560960765, 3500.7199424046016, 3547.113221694576, 3612.566492020922, 3615.3492371373404, 3621.6740493355196, 1198.808190689503, 1220.2800347794844, 1222.1261797814427, 1226.343906010298, 1234.4753479771061, 780.1145699630846, 798.5807451088225, 803.3693550562897, 810.6635746165393, 814.2020819596828, 4700.506446823103, 4894.853154405374, 4926.162552366035, 4928.753562321869, 5160.225966105114, 510.2856001597793, 514.5831876180306, 514.9407818100966, 520.8385400525849, 529.380624669138, 3177.3804108199142, 3179.3323402085357, 3182.840857957093, 3211.229304966791, 3289.983550082234, 8630.956845215735, 8896.131812319356, 9007.369484136048, 9024.323175761843, 9044.276457883354, 8974.775630609287, 8979.506127162938, 8984.820303594022, 8988.820223595621, 8990.820183596292, 6929.792106236821, 6966.268541803139, 7017.4035207006955, 7042.718291268292, 7067.0, 4660.766961651836, 4775.283707443911, 4790.185668436305, 4794.0, 4963.503649635008, 8096.271335579826, 8156.8368632625825, 8163.510189388656, 8174.346052315682, 8228.794280142805, 8174.141715120065, 8359.456635318686, 8370.16298370158, 8374.790630234293, 8410.957945210235, 5735.311762588516, 5923.911141332797, 6436.13112229843, 6527.379898909596, 6546.345365463416, 6845.5550389224545, 7008.088948436671, 7043.683034263573, 7204.819879503054, 7208.135023797178, 6312.147860038836, 6388.361163883575, 6441.967790161019, 6567.835804104936, 6568.8357791055605, 6379.808605741835, 6382.648954307558, 6412.0, 6417.839554011187, 6445.903311450238, 5506.476884695948, 5626.74679639409, 5628.071368224197, 5630.8029218977135, 5634.23937768396, 4069.328560787437, 4077.184563087335, 4102.794860256916, 4220.0, 4255.1915136124035, 4272.87181384559, 4281.336392859112, 4296.505901821331, 4406.272964960874, 4468.084042771289], "value": 3901.7167642401914, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.3491800497061, 345.5128269140512, 347.0923534956089, 349.1724612667974, 350.91371388636935, 4828.7585620719, 4866.926996095057, 4885.145099607568, 4887.560119589236, 4894.3882014748115, 4779.9207344804945, 4787.952120478794, 4795.280707893818, 4797.6161907047435, 4807.206810876201, 3630.8184590770356, 3701.666849983501, 3825.2923209206388, 3825.598312177223, 3897.0841852164717, 3856.903577410559, 3878.3406820840382, 3879.941800872989, 3883.708721845873, 3903.160820423608, 3846.576876543578, 3851.7688938663628, 3860.40163774614, 3876.7867767272787, 3889.044326312009, 3717.6839968602835, 3832.559255685604, 3839.9808000959815, 3897.369275738865, 3900.9609903901023, 3514.418511669733, 3517.4196257617464, 3520.1023738946406, 3529.1530032792202, 3570.232400033979, 4738.597219236369, 4744.312074749177, 4751.239801631757, 4754.738489383082, 4778.730103200986, 4223.66210703143, 4391.0, 4395.110102655856, 4414.3157810539415, 4447.177272204653, 5054.797808087672, 5066.265391518246, 5075.873103172414, 5082.771275292584, 5107.897842043139, 3823.8661646842506, 3825.8469661213517, 3850.210706805098, 3866.1881004988986, 3880.417937309426, 5072.239164125337, 5318.25544423781, 5380.461953804627, 5389.78729785799, 5397.731533089721, 3648.5986541480415, 3674.3386190485435, 3682.779033258013, 3693.3592481375945, 3701.555813302421, 3715.331240376758, 3727.310447567209, 3735.701143908481, 3743.1952130292116, 3755.3803622401997, 4233.962679143629, 4234.882558720639, 4243.745375277432, 4276.914461710749, 4292.527821939584, 4905.411350637946, 5072.721000344943, 5135.460776618401, 5148.330717006766, 5161.406438259573, 1377.5454100146928, 1379.3034517568565, 1389.8818600418876, 1390.930453477322, 1405.1779708870424, 945.9310978594144, 986.5018165826211, 994.7913285358325, 995.1789773436943, 998.4308943902051, 4218.8312467500955, 4321.783910804447, 4323.827046918151, 4345.478542574911, 4348.934765978513, 5132.538071573576, 5151.562117220078, 5202.505761952609, 5242.7378631068295, 5254.868628284324, 4386.885311428921, 4397.516273209944, 4397.868063958086, 4401.170981246272, 4403.779811009436, 2623.5933430318332, 2676.474409586517, 2715.9815069348992, 2717.904873329424, 2723.850188239666, 4719.716816990992, 4740.715557066587, 4744.715317080986, 4746.786394612251, 4772.236442169236, 5000.898006425729, 5221.0, 5223.503767142115, 5232.502912223333, 5241.397239317527, 4667.965822215519, 4744.383230180124, 4745.217039188496, 4750.857474275777, 4764.33299338086, 5104.387473503203, 5115.028144652504, 5116.74416279192, 5141.845744627667, 5148.150555158357, 4877.341558889504, 5018.920931999638, 5022.673526220784, 5058.570021548062, 5098.490150984871, 4698.2952557116705, 4710.199266124699, 4710.835120770894, 4738.455077665974, 4764.857054288377, 3736.0, 3736.869209577652, 3742.9625703743704, 3777.981110094432, 3778.8299526520823, 5117.799346281135, 5134.460881607378, 5143.8714032148, 5148.700834802981, 5164.251183578435, 4723.504032076583, 4945.653804233669, 4994.850154495371, 5009.5491405773655, 5024.673396229379, 5011.241568823382, 5013.423456302572, 5033.974830125826, 5039.6220283479615, 5057.494250574913, 4907.55831975124, 4907.895723462192, 4923.97538012322, 4939.456659767495, 4993.875153121201, 4837.56461918415, 4907.435644900739, 5013.899722005613, 5125.821525851424, 5161.380634323755, 4869.732164730832, 4995.475475075064, 5003.0, 5008.373953255843, 5017.749112544431], "value": 3851.8316462400066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.4012469027256, 691.4261163234515, 730.361608383281, 736.0688728758122, 744.374725230806, 11210.887891121087, 12314.568990085349, 12597.685057873558, 12635.777876641467, 12659.0, 12998.0, 13057.934710326435, 13172.1438106523, 13219.801702974462, 13242.271675057867, 655.5869802024718, 672.1598002496879, 799.8520273749375, 815.9102498725135, 815.963281652327, 792.5126047480816, 816.7958010497375, 818.1368656067846, 819.7704642700023, 824.9505029698207, 857.5712143928037, 861.4701958295666, 864.3128712673408, 864.5461132905243, 865.9653613855438, 693.1128155960379, 709.2588245283691, 856.4005196362509, 856.751542052804, 857.0272740439584, 610.6671863834221, 625.6496362037274, 628.6950828848005, 631.5105793010454, 632.3486808587163, 12801.231926084465, 12804.80792788109, 12807.743845123045, 12808.423620937081, 12843.100982931199, 11534.192606517547, 11971.162018658697, 12181.573644922473, 12252.264864108181, 12349.814752778715, 10223.0, 10721.517531711092, 10789.460526973699, 10825.24223304369, 11416.657500275005, 3267.1668724475344, 3329.6503867093847, 3337.916552086193, 3344.2308269098244, 3345.280764635591, 61872.0, 62533.0, 62695.68652156756, 63260.0, 64118.0, 10574.206934479791, 10657.0, 10703.83944240837, 10744.785104297871, 10845.13238940883, 20329.695054574193, 20432.591348172955, 20478.078486468145, 20480.487987800127, 20490.795092049186, 6734.494912881553, 6862.3137686230975, 6962.895556566656, 7130.714771409085, 7152.892706609405, 3431.2965842002827, 3450.6894379505966, 3491.633378495272, 3500.754947153675, 3535.8585656573964, 2851.073401144636, 2926.0, 2943.2641839540115, 2945.248961514843, 2957.0, 489.95835353994596, 493.77286448234213, 504.87630530520255, 506.87074795926804, 507.47983317100267, 449.274421808783, 472.2066927561729, 479.26432925459255, 525.2724975908394, 530.8327876718821, 7555.811104722426, 7713.922860771433, 7766.184550622216, 8239.382046346547, 8484.490930544187, 467.2384014057133, 470.32273526122276, 472.88887111528766, 537.57531550075, 602.2892986276148, 3746.5316835395574, 4243.384709217208, 4244.691637987446, 4245.596668316505, 4613.515580864028, 47126.5287347129, 51169.0, 53194.468055319725, 53371.19943200855, 55844.603884903205, 43036.784816076346, 48286.5171348282, 53106.203406948924, 53301.0, 53570.46429535655, 49110.508894911305, 50312.0, 51158.0, 51335.74332128315, 52369.738151309, 7998.640061197144, 8373.790655233668, 8415.24262816325, 8477.830443391222, 8538.78653033679, 31075.844620777654, 32886.01341959745, 33321.16697082592, 34950.47574286337, 36669.44995825118, 43797.124057518035, 44658.106837863714, 44927.0, 45326.42766798862, 45334.31998519959, 10520.789584208425, 10964.945175274073, 10965.780684386427, 11214.439278035903, 11391.145664075393, 12815.551455699006, 12822.358882056047, 12889.8066529004, 12923.870761292266, 12949.676258093623, 11774.587889423829, 11806.232594881305, 11850.76298474043, 12381.306557580265, 12517.499300027897, 11338.773224535627, 11584.710382240512, 11696.824547631622, 11971.281723096643, 11984.76030479403, 11528.654140375802, 11550.0, 11609.5356185755, 11800.055995520166, 11879.881201188224, 9846.704598862045, 9861.309708320348, 10013.499325033865, 10276.743081423025, 10362.430066346122, 9191.310651701282, 9570.952145239507, 9631.470269135265, 9687.370320929118, 9696.272779541421], "value": 6159.979041433964, "unit": "score"}], "name": "dromaeo_css", "value": 6178.983652636211, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 114.0, 117.0, 114.0, 117.0, 114.0, 117.0, 116.0, 115.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 132.0, 127.0, 169.0, 128.0, 128.0, 129.0, 132.0, 147.0, 125.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 161.0, 163.0, 162.0, 160.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 81.0, 116.0, 77.0, 77.0, 79.0, 78.0, 100.0, 76.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 114.0, 84.0, 85.0, 86.0, 110.0, 86.0, 85.0, 118.0], "value": 93.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 148.0, 149.0, 150.0, 148.0, 149.0, 148.0, 150.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 67.0, 63.0, 64.0, 63.0, 63.0, 63.0, 64.0, 65.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 67.0, 68.0, 66.0, 67.0, 66.0, 67.0, 68.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 94.0, 84.0, 85.0, 84.0, 94.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 133.0, 135.0, 143.0, 137.0, 132.0, 131.0, 183.0, 144.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 180.0, 182.0, 176.0, 187.0, 180.0, 185.0, 182.0, 184.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 70.0, 70.0, 69.0, 70.0, 69.0, 68.0, 68.0, 70.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1561.7, "alertThreshold": 2.0}]} 17:10:44 INFO - Return code: 0 17:10:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:10:44 INFO - # TBPL SUCCESS # 17:10:44 INFO - Running post-action listener: _resource_record_post_action 17:10:44 INFO - [mozharness: 2016-12-29 01:10:44.862629Z] Finished run-tests step (success) 17:10:44 INFO - Running post-run listener: _resource_record_post_run 17:10:44 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 404889600; Read time: 20; Write time: 306776 17:10:44 INFO - TinderboxPrint: CPU usage
13.1% 17:10:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 17:10:44 INFO - TinderboxPrint: I/O write bytes / time
404,889,600 / 306,776 17:10:44 INFO - TinderboxPrint: CPU idle
8,340.3 (86.9%) 17:10:44 INFO - TinderboxPrint: CPU user
1,215.1 (12.7%) 17:10:44 INFO - TinderboxPrint: Swap in / out
0 / 0 17:10:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110534656; Read time: 0; Write time: 68496 17:10:44 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 294354944; Read time: 0; Write time: 238280 17:10:45 INFO - Running post-run listener: _upload_blobber_files 17:10:45 INFO - Blob upload gear active. 17:10:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:10:45 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 17:10:45 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'] 17:10:45 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 17:10:45 INFO - (blobuploader) - INFO - Open directory for files ... 17:10:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:10:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:10:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:46 INFO - (blobuploader) - INFO - Done attempting. 17:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:47 INFO - (blobuploader) - INFO - Done attempting. 17:10:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:10:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:51 INFO - (blobuploader) - INFO - Done attempting. 17:10:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:10:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:10:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:51 INFO - (blobuploader) - INFO - Done attempting. 17:10:51 INFO - (blobuploader) - INFO - Iteration through files over. 17:10:51 INFO - Return code: 0 17:10:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:10:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:10:51 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90008cee4e7456b5588690436aea8bea169759b7c86d042ddfc08a0516b047aac391b935dc6fa3c21867b1fcc75d2860a49405f01c763b97a69da517a062efcf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e43fa1dd91c3754107dfe038f653ad71b7f225590520927ed1e1f17880d1d1ab9353906ff01e582911c4f4db3dfda3536e014959b6da4dccd53e66adac843d9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e4e2bdaa89a4370260934415902c18738b5d2450dc721438eb2160b5b4ce390f2bae7a47393ff5f748ea5f31d7c60b043994fa26586d632de775f26d9f89d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5dab4d5ed0ef85934437e0b8ab2efcc862d4d2531bc2e8970eed462b025ec158231babbdb6c57ca99e9e3dbbd454544a1cf831768cec8b7ba41f9bd4de4ea442"} 17:10:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:10:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:10:51 INFO - Contents: 17:10:51 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90008cee4e7456b5588690436aea8bea169759b7c86d042ddfc08a0516b047aac391b935dc6fa3c21867b1fcc75d2860a49405f01c763b97a69da517a062efcf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e43fa1dd91c3754107dfe038f653ad71b7f225590520927ed1e1f17880d1d1ab9353906ff01e582911c4f4db3dfda3536e014959b6da4dccd53e66adac843d9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e4e2bdaa89a4370260934415902c18738b5d2450dc721438eb2160b5b4ce390f2bae7a47393ff5f748ea5f31d7c60b043994fa26586d632de775f26d9f89d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5dab4d5ed0ef85934437e0b8ab2efcc862d4d2531bc2e8970eed462b025ec158231babbdb6c57ca99e9e3dbbd454544a1cf831768cec8b7ba41f9bd4de4ea442"} 17:10:51 INFO - Running post-run listener: copy_logs_to_upload_dir 17:10:51 INFO - Copying logs to upload dir... 17:10:51 INFO - mkdir: /builds/slave/test/build/upload/logs 17:10:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.110663 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-28 17:10:50.215682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:10:50.219479) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90008cee4e7456b5588690436aea8bea169759b7c86d042ddfc08a0516b047aac391b935dc6fa3c21867b1fcc75d2860a49405f01c763b97a69da517a062efcf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e43fa1dd91c3754107dfe038f653ad71b7f225590520927ed1e1f17880d1d1ab9353906ff01e582911c4f4db3dfda3536e014959b6da4dccd53e66adac843d9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e4e2bdaa89a4370260934415902c18738b5d2450dc721438eb2160b5b4ce390f2bae7a47393ff5f748ea5f31d7c60b043994fa26586d632de775f26d9f89d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5dab4d5ed0ef85934437e0b8ab2efcc862d4d2531bc2e8970eed462b025ec158231babbdb6c57ca99e9e3dbbd454544a1cf831768cec8b7ba41f9bd4de4ea442"} build_url:https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011562 build_url: 'https://queue.taskcluster.net/v1/task/X6-KeZTcS5iyNFSLZBqS3A/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90008cee4e7456b5588690436aea8bea169759b7c86d042ddfc08a0516b047aac391b935dc6fa3c21867b1fcc75d2860a49405f01c763b97a69da517a062efcf", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e43fa1dd91c3754107dfe038f653ad71b7f225590520927ed1e1f17880d1d1ab9353906ff01e582911c4f4db3dfda3536e014959b6da4dccd53e66adac843d9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4e4e2bdaa89a4370260934415902c18738b5d2450dc721438eb2160b5b4ce390f2bae7a47393ff5f748ea5f31d7c60b043994fa26586d632de775f26d9f89d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5dab4d5ed0ef85934437e0b8ab2efcc862d4d2531bc2e8970eed462b025ec158231babbdb6c57ca99e9e3dbbd454544a1cf831768cec8b7ba41f9bd4de4ea442"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 17:10:50.245046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:10:50.245370) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1482966376.962503-496653087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004604 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 17:10:50.331705) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:10:50.332142) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 17:10:50.332546) ========= ========= Total master_lag: 0.15 =========