builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1481374073.03 results: success (0) buildid: 20161210030206 builduid: 0b3266b46fd04b4aa080ca1d3c1e69f7 revision: 8404d26166a35406f46ff237ed132735c98882b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.026114) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.026622) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.026902) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.042491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.042940) ========= 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=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-10 04:47:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-12-10 04:47:53 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121394 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.178899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.179203) ========= 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=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022017 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.230308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.230712) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8404d26166a35406f46ff237ed132735c98882b2 --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 8404d26166a35406f46ff237ed132735c98882b2 --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=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-10 04:47:54,013 truncating revision to first 12 chars 2016-12-10 04:47:54,013 Setting DEBUG logging. 2016-12-10 04:47:54,013 attempt 1/10 2016-12-10 04:47:54,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8404d26166a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-10 04:47:54,312 unpacking tar archive at: mozilla-central-8404d26166a3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623975 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.882573) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.882990) ========= script_repo_revision: 8404d26166a35406f46ff237ed132735c98882b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.883624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.884020) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 04:47:53.901500) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-10 04:47:53.901959) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:47:54 INFO - MultiFileLogger online at 20161210 04:47:54 in /builds/slave/test 04:47:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:47:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:54 INFO - {'append_to_log': False, 04:47:54 INFO - 'base_work_dir': '/builds/slave/test', 04:47:54 INFO - 'blob_upload_branch': 'Firefox', 04:47:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:54 INFO - 'branch': 'Firefox', 04:47:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:54 INFO - 'config_files': ('talos/linux_config.py',), 04:47:54 INFO - 'default_actions': ('clobber', 04:47:54 INFO - 'read-buildbot-config', 04:47:54 INFO - 'download-and-extract', 04:47:54 INFO - 'populate-webroot', 04:47:54 INFO - 'create-virtualenv', 04:47:54 INFO - 'install', 04:47:54 INFO - 'run-tests'), 04:47:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:54 INFO - 'download_minidump_stackwalk': True, 04:47:54 INFO - 'download_symbols': 'ondemand', 04:47:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:54 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:54 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:54 INFO - 'installer_path': 'installer.exe', 04:47:54 INFO - 'log_level': 'info', 04:47:54 INFO - 'log_name': 'talos', 04:47:54 INFO - 'log_to_console': True, 04:47:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:47:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:47:54 INFO - 'opt_config_files': (), 04:47:54 INFO - 'pip_index': False, 04:47:54 INFO - 'sps_profile': False, 04:47:54 INFO - 'sps_profile_interval': 0, 04:47:54 INFO - 'suite': 'dromaeojs', 04:47:54 INFO - 'system_bits': '32', 04:47:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:54 INFO - 'title': 'talos-linux64-ix-054', 04:47:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:54 INFO - 'use_talos_json': True, 04:47:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:54 INFO - 'work_dir': 'build'} 04:47:54 INFO - [mozharness: 2016-12-10 12:47:54.778999Z] Running clobber step. 04:47:54 INFO - Running pre-action listener: _resource_record_pre_action 04:47:54 INFO - Running main action method: clobber 04:47:54 INFO - rmtree: /builds/slave/test/build 04:47:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:55 INFO - Running post-action listener: _resource_record_post_action 04:47:55 INFO - [mozharness: 2016-12-10 12:47:55.756656Z] Finished clobber step (success) 04:47:55 INFO - [mozharness: 2016-12-10 12:47:55.756753Z] Running read-buildbot-config step. 04:47:55 INFO - Running pre-action listener: _resource_record_pre_action 04:47:55 INFO - Running main action method: read_buildbot_config 04:47:55 INFO - Using buildbot properties: 04:47:55 INFO - { 04:47:55 INFO - "project": "", 04:47:55 INFO - "product": "firefox", 04:47:55 INFO - "script_repo_revision": "production", 04:47:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 04:47:55 INFO - "repository": "", 04:47:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 04:47:55 INFO - "buildid": "20161210030206", 04:47:55 INFO - "stage_platform": "linux64-pgo", 04:47:55 INFO - "basedir": "/builds/slave/test", 04:47:55 INFO - "nightly_build": "True", 04:47:55 INFO - "buildnumber": 102, 04:47:55 INFO - "slavename": "talos-linux64-ix-054", 04:47:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:47:55 INFO - "platform": "ubuntu64_hw", 04:47:55 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 04:47:55 INFO - "branch": "mozilla-central", 04:47:55 INFO - "repo_path": "mozilla-central", 04:47:55 INFO - "revision": "8404d26166a35406f46ff237ed132735c98882b2", 04:47:55 INFO - "pgo_build": "True", 04:47:55 INFO - "builduid": "0b3266b46fd04b4aa080ca1d3c1e69f7", 04:47:55 INFO - "slavebuilddir": "test" 04:47:55 INFO - } 04:47:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 04:47:55 INFO - Running post-action listener: _resource_record_post_action 04:47:55 INFO - [mozharness: 2016-12-10 12:47:55.759227Z] Finished read-buildbot-config step (success) 04:47:55 INFO - [mozharness: 2016-12-10 12:47:55.759313Z] Running download-and-extract step. 04:47:55 INFO - Running pre-action listener: _resource_record_pre_action 04:47:55 INFO - Running main action method: download_and_extract 04:47:55 INFO - mkdir: /builds/slave/test/build/tests 04:47:55 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')]} 04:47:55 INFO - trying https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:47:55 INFO - Downloading https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/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 04:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/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 04:47:56 INFO - Downloaded 1361 bytes. 04:47:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 04:47:56 INFO - Using the following test package requirements: 04:47:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:47:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:47:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:47:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'jsshell-linux-x86_64.zip'], 04:47:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:47:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 04:47:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:47:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:47:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:47:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 04:47:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:47:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:47:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:47:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:47:56 INFO - Fetch https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:47:57 INFO - Expected file size: 21785369 04:47:57 INFO - Obtained file size: 21785369 04:48:01 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 04:48:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 04:48:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:48:01 INFO - Fetch https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 04:48:02 INFO - Expected file size: 21785369 04:48:02 INFO - Obtained file size: 21785369 04:48:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 04:48:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:48:07 INFO - Fetch https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 04:48:08 INFO - Expected file size: 14409734 04:48:08 INFO - Obtained file size: 14409734 04:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:08 INFO - trying https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:48:08 INFO - Downloading https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/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 04:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/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 04:48:11 INFO - Downloaded 59867988 bytes. 04:48:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:48:11 INFO - mkdir: /builds/slave/test/properties 04:48:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:48:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:48:11 INFO - Contents: 04:48:11 INFO - build_url:https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 04:48:12 INFO - Running post-action listener: _resource_record_post_action 04:48:12 INFO - Running post-action listener: set_extra_try_arguments 04:48:12 INFO - [mozharness: 2016-12-10 12:48:12.230217Z] Finished download-and-extract step (success) 04:48:12 INFO - [mozharness: 2016-12-10 12:48:12.230442Z] Running populate-webroot step. 04:48:12 INFO - Running pre-action listener: _resource_record_pre_action 04:48:12 INFO - Running main action method: populate_webroot 04:48:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:48:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:48:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:48:12 INFO - '--tppagecycles', 04:48:12 INFO - '7'], 04:48:12 INFO - 'tests': ['tsvgm']}}, 04:48:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:48:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:48:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:48:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:48:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['damp', 'tps']}, 04:48:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'tests': ['damp', 'tps']}, 04:48:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['dromaeo_dom']}, 04:48:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:48:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['basic_compositor_video']}, 04:48:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:48:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['a11yr', 04:48:12 INFO - 'ts_paint', 04:48:12 INFO - 'tpaint', 04:48:12 INFO - 'sessionrestore', 04:48:12 INFO - 'sessionrestore_no_auto_restore', 04:48:12 INFO - 'tabpaint']}, 04:48:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['tsvgx', 04:48:12 INFO - 'tsvgr_opacity', 04:48:12 INFO - 'tart', 04:48:12 INFO - 'tscrollx', 04:48:12 INFO - 'cart', 04:48:12 INFO - 'tsvg_static']}, 04:48:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:48:12 INFO - 'tsvgr_opacity', 04:48:12 INFO - 'tart', 04:48:12 INFO - 'tscrollx', 04:48:12 INFO - 'cart', 04:48:12 INFO - 'tsvg_static']}, 04:48:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'talos_options': ['--disable-e10s'], 04:48:12 INFO - 'tests': ['tp5o']}, 04:48:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:48:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'talos_options': ['--disable-e10s', 04:48:12 INFO - '--xperf_path', 04:48:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:12 INFO - 'tests': ['tp5n']}, 04:48:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:48:12 INFO - 'talos_options': ['--xperf_path', 04:48:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:12 INFO - 'tests': ['tp5n']}}, 04:48:12 INFO - 'talos.zip': {'path': '', 04:48:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:48:12 INFO - Running post-action listener: _resource_record_post_action 04:48:12 INFO - [mozharness: 2016-12-10 12:48:12.248476Z] Finished populate-webroot step (success) 04:48:12 INFO - [mozharness: 2016-12-10 12:48:12.248563Z] Running create-virtualenv step. 04:48:12 INFO - Running pre-action listener: _resource_record_pre_action 04:48:12 INFO - Running main action method: create_virtualenv 04:48:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:48:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:48:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:48:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:48:12 INFO - Using real prefix '/usr' 04:48:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:48:12 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:14 INFO - Installing pip.................done. 04:48:14 INFO - Return code: 0 04:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:48:15 INFO - Reading from file tmpfile_stdout 04:48:15 INFO - Output received: 04:48:15 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) 04:48:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:48:15 INFO - Using env: {'DISPLAY': ':0', 04:48:15 INFO - 'HOME': '/home/cltbld', 04:48:15 INFO - 'LANG': 'en_US.UTF-8', 04:48:15 INFO - 'LANGUAGE': 'en_US:en', 04:48:15 INFO - 'LOGNAME': 'cltbld', 04:48:15 INFO - 'MAIL': '/var/mail/cltbld', 04:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:15 INFO - 'MOZ_NO_REMOTE': '1', 04:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:15 INFO - 'NO_EM_RESTART': '1', 04:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:15 INFO - 'PWD': '/builds/slave/test', 04:48:15 INFO - 'SHELL': '/bin/bash', 04:48:15 INFO - 'SHLVL': '1', 04:48:15 INFO - 'TERM': 'linux', 04:48:15 INFO - 'TMOUT': '86400', 04:48:15 INFO - 'USER': 'cltbld', 04:48:15 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:15 INFO - '_': '/tools/buildbot/bin/python'} 04:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:48:15 INFO - Cleaning up... 04:48:15 INFO - Return code: 0 04:48:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:48:15 INFO - Using env: {'DISPLAY': ':0', 04:48:15 INFO - 'HOME': '/home/cltbld', 04:48:15 INFO - 'LANG': 'en_US.UTF-8', 04:48:15 INFO - 'LANGUAGE': 'en_US:en', 04:48:15 INFO - 'LOGNAME': 'cltbld', 04:48:15 INFO - 'MAIL': '/var/mail/cltbld', 04:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:15 INFO - 'MOZ_NO_REMOTE': '1', 04:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:15 INFO - 'NO_EM_RESTART': '1', 04:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:15 INFO - 'PWD': '/builds/slave/test', 04:48:15 INFO - 'SHELL': '/bin/bash', 04:48:15 INFO - 'SHLVL': '1', 04:48:15 INFO - 'TERM': 'linux', 04:48:15 INFO - 'TMOUT': '86400', 04:48:15 INFO - 'USER': 'cltbld', 04:48:15 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:15 INFO - '_': '/tools/buildbot/bin/python'} 04:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:15 INFO - Downloading/unpacking psutil>=3.1.1 04:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:17 INFO - Installing collected packages: psutil 04:48:17 INFO - Running setup.py install for psutil 04:48:17 INFO - building 'psutil._psutil_linux' extension 04:48:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:48:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:48:17 INFO - building 'psutil._psutil_posix' extension 04:48:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:48:17 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:48:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:18 INFO - Successfully installed psutil 04:48:18 INFO - Cleaning up... 04:48:18 INFO - Return code: 0 04:48:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:48:18 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')]} 04:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:18 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')]} 04:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:18 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 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:18 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 04:48:18 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 04:48:18 INFO - Using env: {'DISPLAY': ':0', 04:48:18 INFO - 'HOME': '/home/cltbld', 04:48:18 INFO - 'LANG': 'en_US.UTF-8', 04:48:18 INFO - 'LANGUAGE': 'en_US:en', 04:48:18 INFO - 'LOGNAME': 'cltbld', 04:48:18 INFO - 'MAIL': '/var/mail/cltbld', 04:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:18 INFO - 'MOZ_NO_REMOTE': '1', 04:48:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:18 INFO - 'NO_EM_RESTART': '1', 04:48:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:18 INFO - 'PWD': '/builds/slave/test', 04:48:18 INFO - 'SHELL': '/bin/bash', 04:48:18 INFO - 'SHLVL': '1', 04:48:18 INFO - 'TERM': 'linux', 04:48:18 INFO - 'TMOUT': '86400', 04:48:18 INFO - 'USER': 'cltbld', 04:48:18 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:18 INFO - '_': '/tools/buildbot/bin/python'} 04:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:48:20 INFO - Installing collected packages: mozsystemmonitor 04:48:20 INFO - Running setup.py install for mozsystemmonitor 04:48:20 INFO - Successfully installed mozsystemmonitor 04:48:20 INFO - Cleaning up... 04:48:20 INFO - Return code: 0 04:48:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:48:20 INFO - Using env: {'DISPLAY': ':0', 04:48:20 INFO - 'HOME': '/home/cltbld', 04:48:20 INFO - 'LANG': 'en_US.UTF-8', 04:48:20 INFO - 'LANGUAGE': 'en_US:en', 04:48:20 INFO - 'LOGNAME': 'cltbld', 04:48:20 INFO - 'MAIL': '/var/mail/cltbld', 04:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:20 INFO - 'MOZ_NO_REMOTE': '1', 04:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:20 INFO - 'NO_EM_RESTART': '1', 04:48:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:20 INFO - 'PWD': '/builds/slave/test', 04:48:20 INFO - 'SHELL': '/bin/bash', 04:48:20 INFO - 'SHLVL': '1', 04:48:20 INFO - 'TERM': 'linux', 04:48:20 INFO - 'TMOUT': '86400', 04:48:20 INFO - 'USER': 'cltbld', 04:48:20 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:20 INFO - '_': '/tools/buildbot/bin/python'} 04:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:20 INFO - Downloading/unpacking jsonschema==2.5.1 04:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:22 INFO - warning: no files found matching '*.txt' 04:48:22 INFO - no previously-included directories found matching 'build' 04:48:22 INFO - no previously-included directories found matching 'dist' 04:48:22 INFO - no previously-included directories found matching '.git*' 04:48:23 INFO - Installing collected packages: jsonschema, functools32 04:48:23 INFO - Running setup.py install for functools32 04:48:23 INFO - warning: no files found matching '*.txt' 04:48:23 INFO - no previously-included directories found matching 'build' 04:48:23 INFO - no previously-included directories found matching 'dist' 04:48:23 INFO - no previously-included directories found matching '.git*' 04:48:23 INFO - Successfully installed jsonschema functools32 04:48:23 INFO - Cleaning up... 04:48:23 INFO - Return code: 0 04:48:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:48:23 INFO - Using env: {'DISPLAY': ':0', 04:48:23 INFO - 'HOME': '/home/cltbld', 04:48:23 INFO - 'LANG': 'en_US.UTF-8', 04:48:23 INFO - 'LANGUAGE': 'en_US:en', 04:48:23 INFO - 'LOGNAME': 'cltbld', 04:48:23 INFO - 'MAIL': '/var/mail/cltbld', 04:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:23 INFO - 'MOZ_NO_REMOTE': '1', 04:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:23 INFO - 'NO_EM_RESTART': '1', 04:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:23 INFO - 'PWD': '/builds/slave/test', 04:48:23 INFO - 'SHELL': '/bin/bash', 04:48:23 INFO - 'SHLVL': '1', 04:48:23 INFO - 'TERM': 'linux', 04:48:23 INFO - 'TMOUT': '86400', 04:48:23 INFO - 'USER': 'cltbld', 04:48:23 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:23 INFO - '_': '/tools/buildbot/bin/python'} 04:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:48:23 INFO - Cleaning up... 04:48:23 INFO - Return code: 0 04:48:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:48:23 INFO - Using env: {'DISPLAY': ':0', 04:48:23 INFO - 'HOME': '/home/cltbld', 04:48:23 INFO - 'LANG': 'en_US.UTF-8', 04:48:23 INFO - 'LANGUAGE': 'en_US:en', 04:48:23 INFO - 'LOGNAME': 'cltbld', 04:48:23 INFO - 'MAIL': '/var/mail/cltbld', 04:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:23 INFO - 'MOZ_NO_REMOTE': '1', 04:48:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:23 INFO - 'NO_EM_RESTART': '1', 04:48:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:23 INFO - 'PWD': '/builds/slave/test', 04:48:23 INFO - 'SHELL': '/bin/bash', 04:48:23 INFO - 'SHLVL': '1', 04:48:23 INFO - 'TERM': 'linux', 04:48:23 INFO - 'TMOUT': '86400', 04:48:23 INFO - 'USER': 'cltbld', 04:48:23 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:23 INFO - '_': '/tools/buildbot/bin/python'} 04:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:48:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:26 INFO - Downloading docopt-0.6.1.tar.gz 04:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:26 INFO - Running setup.py install for blobuploader 04:48:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:26 INFO - Running setup.py install for requests 04:48:26 INFO - Running setup.py install for docopt 04:48:26 INFO - Successfully installed blobuploader requests docopt 04:48:26 INFO - Cleaning up... 04:48:27 INFO - Return code: 0 04:48:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:27 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 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:27 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 04:48:27 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 04:48:27 INFO - Using env: {'DISPLAY': ':0', 04:48:27 INFO - 'HOME': '/home/cltbld', 04:48:27 INFO - 'LANG': 'en_US.UTF-8', 04:48:27 INFO - 'LANGUAGE': 'en_US:en', 04:48:27 INFO - 'LOGNAME': 'cltbld', 04:48:27 INFO - 'MAIL': '/var/mail/cltbld', 04:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:27 INFO - 'MOZ_NO_REMOTE': '1', 04:48:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:27 INFO - 'NO_EM_RESTART': '1', 04:48:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:27 INFO - 'PWD': '/builds/slave/test', 04:48:27 INFO - 'SHELL': '/bin/bash', 04:48:27 INFO - 'SHLVL': '1', 04:48:27 INFO - 'TERM': 'linux', 04:48:27 INFO - 'TMOUT': '86400', 04:48:27 INFO - 'USER': 'cltbld', 04:48:27 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:27 INFO - '_': '/tools/buildbot/bin/python'} 04:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:27 INFO - Running setup.py (path:/tmp/pip-RIxPvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:27 INFO - Running setup.py (path:/tmp/pip-E9ZWst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:27 INFO - Running setup.py (path:/tmp/pip-oqubRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:27 INFO - Running setup.py (path:/tmp/pip-tYdOQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:27 INFO - Running setup.py (path:/tmp/pip-yt7iT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:27 INFO - Running setup.py (path:/tmp/pip-rQG2u4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:27 INFO - Running setup.py (path:/tmp/pip-IcV51f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:27 INFO - Running setup.py (path:/tmp/pip-IjVxDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:27 INFO - Running setup.py (path:/tmp/pip-o8juYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:27 INFO - Running setup.py (path:/tmp/pip-dx79g7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:28 INFO - Running setup.py (path:/tmp/pip-bXyor6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:28 INFO - Running setup.py (path:/tmp/pip-n0eTIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:28 INFO - Running setup.py (path:/tmp/pip-tfEJmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:28 INFO - Running setup.py (path:/tmp/pip-2RcwYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:28 INFO - Running setup.py (path:/tmp/pip-CkdIOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:28 INFO - Running setup.py (path:/tmp/pip-ARHRyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:28 INFO - Running setup.py (path:/tmp/pip-uEvevu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:28 INFO - Running setup.py install for manifestparser 04:48:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:28 INFO - Running setup.py install for mozcrash 04:48:28 INFO - Running setup.py install for mozdebug 04:48:28 INFO - Running setup.py install for mozdevice 04:48:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:28 INFO - Running setup.py install for mozfile 04:48:29 INFO - Running setup.py install for mozhttpd 04:48:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozinfo 04:48:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozInstall 04:48:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozleak 04:48:29 INFO - Running setup.py install for mozlog 04:48:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for moznetwork 04:48:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozprocess 04:48:29 INFO - Running setup.py install for mozprofile 04:48:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozrunner 04:48:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozscreenshot 04:48:30 INFO - Running setup.py install for moztest 04:48:30 INFO - Running setup.py install for mozversion 04:48:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:30 INFO - Cleaning up... 04:48:30 INFO - Return code: 0 04:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:30 INFO - Using env: {'DISPLAY': ':0', 04:48:30 INFO - 'HOME': '/home/cltbld', 04:48:30 INFO - 'LANG': 'en_US.UTF-8', 04:48:30 INFO - 'LANGUAGE': 'en_US:en', 04:48:30 INFO - 'LOGNAME': 'cltbld', 04:48:30 INFO - 'MAIL': '/var/mail/cltbld', 04:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:30 INFO - 'MOZ_NO_REMOTE': '1', 04:48:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:30 INFO - 'NO_EM_RESTART': '1', 04:48:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:30 INFO - 'PWD': '/builds/slave/test', 04:48:30 INFO - 'SHELL': '/bin/bash', 04:48:30 INFO - 'SHLVL': '1', 04:48:30 INFO - 'TERM': 'linux', 04:48:30 INFO - 'TMOUT': '86400', 04:48:30 INFO - 'USER': 'cltbld', 04:48:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:30 INFO - '_': '/tools/buildbot/bin/python'} 04:48:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:30 INFO - Running setup.py (path:/tmp/pip-hZrOCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:30 INFO - Running setup.py (path:/tmp/pip-fDRkAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:30 INFO - Running setup.py (path:/tmp/pip-8NgQK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:30 INFO - Running setup.py (path:/tmp/pip-vHTDOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:30 INFO - Running setup.py (path:/tmp/pip-ndLpCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:30 INFO - Running setup.py (path:/tmp/pip-PUkUg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:30 INFO - Running setup.py (path:/tmp/pip-XgIvBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:31 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)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:31 INFO - Running setup.py (path:/tmp/pip-cB4tbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:31 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)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:31 INFO - Running setup.py (path:/tmp/pip-IfCslx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:31 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)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:31 INFO - Running setup.py (path:/tmp/pip-tF738y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:31 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)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:31 INFO - Running setup.py (path:/tmp/pip-IzFUjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:31 INFO - Running setup.py (path:/tmp/pip-lpcmY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:31 INFO - Running setup.py (path:/tmp/pip-hGvNdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:31 INFO - Running setup.py (path:/tmp/pip-SfzXd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:31 INFO - Running setup.py (path:/tmp/pip-oQH4wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:31 INFO - Running setup.py (path:/tmp/pip-Yznolj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:31 INFO - Running setup.py (path:/tmp/pip-qgrPLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:33 INFO - Downloading blessings-1.6.tar.gz 04:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:33 INFO - Installing collected packages: blessings 04:48:33 INFO - Running setup.py install for blessings 04:48:33 INFO - Successfully installed blessings 04:48:33 INFO - Cleaning up... 04:48:34 INFO - Return code: 0 04:48:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:34 INFO - Reading from file tmpfile_stdout 04:48:34 INFO - Current package versions: 04:48:34 INFO - argparse == 1.2.1 04:48:34 INFO - blessings == 1.6 04:48:34 INFO - blobuploader == 1.2.4 04:48:34 INFO - docopt == 0.6.1 04:48:34 INFO - functools32 == 3.2.3-2 04:48:34 INFO - jsonschema == 2.5.1 04:48:34 INFO - manifestparser == 1.1 04:48:34 INFO - mozInstall == 1.12 04:48:34 INFO - mozcrash == 1.0 04:48:34 INFO - mozdebug == 0.1 04:48:34 INFO - mozdevice == 0.49 04:48:34 INFO - mozfile == 1.2 04:48:34 INFO - mozhttpd == 0.7 04:48:34 INFO - mozinfo == 0.9 04:48:34 INFO - mozleak == 0.1 04:48:34 INFO - mozlog == 3.4 04:48:34 INFO - moznetwork == 0.27 04:48:34 INFO - mozprocess == 0.23 04:48:34 INFO - mozprofile == 0.28 04:48:34 INFO - mozrunner == 6.13 04:48:34 INFO - mozscreenshot == 0.1 04:48:34 INFO - mozsystemmonitor == 0.3 04:48:34 INFO - moztest == 0.7 04:48:34 INFO - mozversion == 1.4 04:48:34 INFO - psutil == 3.1.1 04:48:34 INFO - requests == 1.2.3 04:48:34 INFO - wsgiref == 0.1.2 04:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:34 INFO - Using env: {'DISPLAY': ':0', 04:48:34 INFO - 'HOME': '/home/cltbld', 04:48:34 INFO - 'LANG': 'en_US.UTF-8', 04:48:34 INFO - 'LANGUAGE': 'en_US:en', 04:48:34 INFO - 'LOGNAME': 'cltbld', 04:48:34 INFO - 'MAIL': '/var/mail/cltbld', 04:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:34 INFO - 'MOZ_NO_REMOTE': '1', 04:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:34 INFO - 'NO_EM_RESTART': '1', 04:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:34 INFO - 'PWD': '/builds/slave/test', 04:48:34 INFO - 'SHELL': '/bin/bash', 04:48:34 INFO - 'SHLVL': '1', 04:48:34 INFO - 'TERM': 'linux', 04:48:34 INFO - 'TMOUT': '86400', 04:48:34 INFO - 'USER': 'cltbld', 04:48:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:34 INFO - '_': '/tools/buildbot/bin/python'} 04:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 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)) 04:48:34 INFO - Cleaning up... 04:48:34 INFO - Return code: 0 04:48:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f445e937e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, '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': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', '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 04:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:48:34 INFO - Using env: {'DISPLAY': ':0', 04:48:34 INFO - 'HOME': '/home/cltbld', 04:48:34 INFO - 'LANG': 'en_US.UTF-8', 04:48:34 INFO - 'LANGUAGE': 'en_US:en', 04:48:34 INFO - 'LOGNAME': 'cltbld', 04:48:34 INFO - 'MAIL': '/var/mail/cltbld', 04:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:34 INFO - 'MOZ_NO_REMOTE': '1', 04:48:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:34 INFO - 'NO_EM_RESTART': '1', 04:48:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:34 INFO - 'PWD': '/builds/slave/test', 04:48:34 INFO - 'SHELL': '/bin/bash', 04:48:34 INFO - 'SHLVL': '1', 04:48:34 INFO - 'TERM': 'linux', 04:48:34 INFO - 'TMOUT': '86400', 04:48:34 INFO - 'USER': 'cltbld', 04:48:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:34 INFO - '_': '/tools/buildbot/bin/python'} 04:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:48:34 INFO - Cleaning up... 04:48:34 INFO - Return code: 0 04:48:34 INFO - Running post-action listener: _resource_record_post_action 04:48:34 INFO - Running post-action listener: _start_resource_monitoring 04:48:34 INFO - Starting resource monitoring. 04:48:34 INFO - [mozharness: 2016-12-10 12:48:34.741405Z] Finished create-virtualenv step (success) 04:48:34 INFO - [mozharness: 2016-12-10 12:48:34.741929Z] Running install step. 04:48:34 INFO - Running pre-action listener: _resource_record_pre_action 04:48:34 INFO - Running main action method: install 04:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:34 INFO - Reading from file tmpfile_stdout 04:48:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:35 INFO - Reading from file tmpfile_stdout 04:48:35 INFO - Output received: 04:48:35 INFO - Usage: mozinstall [options] installer 04:48:35 INFO - Options: 04:48:35 INFO - -h, --help show this help message and exit 04:48:35 INFO - -d DEST, --destination=DEST 04:48:35 INFO - Directory to install application into. [default: 04:48:35 INFO - "/builds/slave/test"] 04:48:35 INFO - --app=APP Application being installed. [default: firefox] 04:48:35 INFO - mkdir: /builds/slave/test/build/application 04:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:48:52 INFO - Reading from file tmpfile_stdout 04:48:52 INFO - Output received: 04:48:52 INFO - /builds/slave/test/build/application/firefox/firefox 04:48:52 INFO - Running post-action listener: _resource_record_post_action 04:48:52 INFO - [mozharness: 2016-12-10 12:48:52.008790Z] Finished install step (success) 04:48:52 INFO - [mozharness: 2016-12-10 12:48:52.009032Z] Running run-tests step. 04:48:52 INFO - Running pre-action listener: _resource_record_pre_action 04:48:52 INFO - Running main action method: run_tests 04:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:52 INFO - Python 2.7.3 04:48:52 INFO - Return code: 0 04:48:52 INFO - grabbing minidump binary from tooltool 04:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bdef20>, '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 0x1b4bc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:48:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:48:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:48:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:52 INFO - Return code: 0 04:48:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:48:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:48:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 04:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 04:48:52 INFO - Using env: {'DISPLAY': ':0', 04:48:52 INFO - 'HOME': '/home/cltbld', 04:48:52 INFO - 'LANG': 'en_US.UTF-8', 04:48:52 INFO - 'LANGUAGE': 'en_US:en', 04:48:52 INFO - 'LOGNAME': 'cltbld', 04:48:52 INFO - 'MAIL': '/var/mail/cltbld', 04:48:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:52 INFO - 'MOZ_NO_REMOTE': '1', 04:48:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:48:52 INFO - 'NO_EM_RESTART': '1', 04:48:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:52 INFO - 'PWD': '/builds/slave/test', 04:48:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:52 INFO - 'SHELL': '/bin/bash', 04:48:52 INFO - 'SHLVL': '1', 04:48:52 INFO - 'TERM': 'linux', 04:48:52 INFO - 'TMOUT': '86400', 04:48:52 INFO - 'USER': 'cltbld', 04:48:52 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274', 04:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:52 INFO - '_': '/tools/buildbot/bin/python'} 04:48:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 04:48:52 INFO - mozversion application_buildid: 20161210030206 04:48:52 INFO - mozversion application_changeset: 8404d26166a35406f46ff237ed132735c98882b2 04:48:52 INFO - mozversion application_display_name: Nightly 04:48:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:52 INFO - mozversion application_name: Firefox 04:48:52 INFO - mozversion application_remotingname: firefox 04:48:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:48:52 INFO - mozversion application_vendor: Mozilla 04:48:52 INFO - mozversion application_version: 53.0a1 04:48:52 INFO - mozversion platform_buildid: 20161210030206 04:48:52 INFO - mozversion platform_changeset: 8404d26166a35406f46ff237ed132735c98882b2 04:48:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:48:52 INFO - mozversion platform_version: 53.0a1 04:48:52 INFO - using testdate: 1481374132 04:48:52 INFO - actual date: 1481374132 04:48:52 INFO - starting webserver on 'localhost:39729' 04:48:52 INFO - SUITE-START | Running 2 tests 04:48:52 INFO - TEST-START | dromaeo_css 04:48:52 INFO - operating with platform_type : linux_ 04:48:52 INFO - Initialising browser for dromaeo_css test... 04:48:52 INFO - TEST-INFO | started process 4316 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOy3vPG/profile http://localhost:39729/getInfo.html) 04:48:56 INFO - PROCESS | 4316 | __metrics Screen width/height:1600/1200 04:48:56 INFO - PROCESS | 4316 | colorDepth:24 04:48:56 INFO - PROCESS | 4316 | Browser inner width/height: 1024/697 04:48:56 INFO - PROCESS | 4316 | __metrics 04:48:57 INFO - PROCESS | 4316 | 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" 04:48:57 INFO - PROCESS | 4316 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:57 INFO - PROCESS | 4316 | console.error: PushService: 04:48:57 INFO - PROCESS | 4316 | stateChangeProcessEnqueue: Error transitioning state 04:48:57 INFO - PROCESS | 4316 | UnknownError 04:48:58 INFO - TEST-INFO | 4316: exit 0 04:48:58 INFO - Browser initialized. 04:48:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:58 INFO - TEST-INFO | started process 4398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOy3vPG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:10 INFO - PROCESS | 4398 | RSS: Main: 172371968 04:49:10 INFO - PROCESS | 4398 | 04:50:01 INFO - PROCESS | 4398 | 1481374201584 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:52:13 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39729/tests/dromaeo/cssquery-ext.html) 04:52:14 INFO - PROCESS | 4398 | RSS: Main: 288489472 04:52:14 INFO - PROCESS | 4398 | 04:55:17 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-ext.html (next: http://localhost:39729/tests/dromaeo/cssquery-jquery.html) 04:55:17 INFO - PROCESS | 4398 | RSS: Main: 287748096 04:55:17 INFO - PROCESS | 4398 | 04:58:19 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39729/tests/dromaeo/cssquery-mootools.html) 04:58:20 INFO - PROCESS | 4398 | RSS: Main: 281976832 04:58:20 INFO - PROCESS | 4398 | 05:01:22 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39729/tests/dromaeo/cssquery-prototype.html) 05:01:23 INFO - PROCESS | 4398 | RSS: Main: 235782144 05:01:23 INFO - PROCESS | 4398 | 05:04:25 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39729/tests/dromaeo/cssquery-yui.html) 05:04:26 INFO - PROCESS | 4398 | RSS: Main: 222650368 05:04:26 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | Cycle 1(1): loaded http://localhost:39729/tests/dromaeo/cssquery-yui.html (next: http://localhost:39729/tests/dromaeo/cssquery-dojo.html) 05:07:29 INFO - PROCESS | 4398 | RSS: Main: 316899328 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | __start_tp_report 05:07:29 INFO - PROCESS | 4398 | _x_x_mozilla_page_load 05:07:29 INFO - PROCESS | 4398 | _x_x_mozilla_page_load_details 05:07:29 INFO - PROCESS | 4398 | |i|pagename|runs| 05:07:29 INFO - PROCESS | 4398 | |0;dojo.html;3254.9023529294122;3286.326303107862;3288.917777055574;3313.370459612675;3337.5494308268376;5209.687418754877;5271.15661494161;5298.814541491044;5360.27636269104;5387.4073851876265;5268.762905669245;5282.656627319221;5284.365876094874;5284.471552844713;5291.629585928987;4762.047590481909;4780.90438191236;4794.640401969867;4818.253170758538;4837.7339246341435;4868.026708821006;4938.629602779788;4945.703257804544;4946.208606622941;4952.331435256248;4860.1494738420815;4904.386951631046;4909.8036078557;4930;4946.1344264753525;4893.09477746618;4935.629827762897;4960.677555958851;4962.305277261186;4967.081089998362;4709.411323584552;4746.21687421575;4785.353977213066;4817.879553011203;4855.126077306083;5314.388845282765;5317.12267475866;5331.306930099101;5351.892962140735;5373.704446255454;5670.121131224667;5721.427857214287;5722.084466485384;5725.427457254283;5728.518057285184;5581.748821303051;5634.690092044936;5657.830265092054;5710.8001219957105;5795.82612521625;5319.9202011969855;5345.278387417688;5346.893062138735;5347.304850369427;5347.331583552056;5783.913241301384;5797.768089276466;5805.216295800097;5836.737346819404;5860.238169037998;5605.971970140124;5660.150977353348;5661.971690141523;5663.915041274384;5700.857478562986;5707.4577915098;5757.453041961007;5758.971205144031;5762.625429347163;5765.567582431335;5575.219469274305;5625.324961004706;5627.43725627434;5686.488216060492;5707.571932105108;5678.346990096109;5685.431456854364;5686.77252909879;5688.914666280009;5693.715314234271;4387.912241755147;4421.358902959053;4424.3142312941545;4427.579379958963;4428.535003824552;4029.576894426043;4044.4944381952255;4044.6359827615656;4046.5144182698264;4048.1296521248073;5409.702466364309;5411.729413529308;5417.83746487606;5422.349318081855;5424.484673955968;5667.149927510906;5681.857953551112;5697.544196464273;5698.430156984268;5699.74351154199;5364.892702146013;5365.973170134124;5367.946320536745;5372.274742909735;5386.192071189353;4892.804287828518;4899.461059283476;4904.779284932187;4905.852824415273;4951.282064100757;5677.205191273142;5700.572457065819;5798.94201057984;5917.112433135064;5926.466618004314;5606.719664016701;5710;5732;5739.454751798572;5816.534677225727;5926.288845338587;5926.703664816656;5930.318013428511;5930.881382372242;5972.820815375546;5661.264035675335;5676.545876329801;5679.971600141973;5681.545476361964;5682.516986056232;5612.85967850807;5658.405867383865;5672.234248376416;5691.060974939254;5756.827295181151;5619.100943849128;5622.7750889963945;5629.774809007594;5643;5668.5465162787705;5592.273004509388;5595.188697638738;5598.664080155204;5600.523955463831;5600.551955843604;5368.570514358764;5432.809851655331;5527.198556209408;5586.692731899622;5592.161175823659;5566.276384070045;5657.094864821609;5715.142728590745;5723.399043100415;5725.08398656213;5521.365043019943;5526.806561770319;5529.502344788988;5531.751071201886;5533.170024496358 05:07:29 INFO - PROCESS | 4398 | |1;ext.html;549.5987928811969;551.6497024389513;576.5963825322275;579.7680927628948;588.9676067816268;31335.686643133562;39448.80275598622;40949.3857592136;40977.38533921994;44904.10191796162;43252.3512147318;43749.78125109378;43790.34314485277;43843.56156438443;43874.341884871676;8471.703490377853;8662.220400163984;8668.046514883357;8673.13268673134;8674.479531228115;7970.721024764134;8178.345732341428;10024.89975100247;10290.639827606035;10292.948535257314;10235.334703244302;10303.948480257588;10306.381617102998;10307.278490505667;10307.63923262686;10214.182865370753;10271.178305735522;10272.537735801907;10274.7431314217;10297.227707921937;10136.79726405475;10214.080732734015;10214.438205898672;10215.846762298572;10217.795644087151;41178.38232426516;41471.1705765886;41476.37785433221;41486;41490.377644335356;3340.682635149657;3413.8975830725117;3427.5544179256785;3636.890893273206;4047.655949244318;28413;28627;29142;31297.217569560715;31518.684813151805;4025.7383270087644;4046.433499310099;4117.382392641128;4145.834166633331;4155.085881106152;347701;347890;348977;349511;350112;50684.49315506872;50795;50896.23655645168;50963.432559720466;50984.92120630341;53576;53603.73198133984;53886.46113538893;53920.46079539155;53922.460775391744;15190.924045379703;15842.52472425806;15852.1281329528;15859.572638462363;15860.286287117107;10904.61833835812;10972.122230221563;10976.835347469794;11021.118310535137;11468.197226194254;16260.593485162966;16501.91749041247;16618.25217865199;17099.829001710073;17163.656726865393;6392.155490806639;6412.23053233615;6546.607203567801;6613.966930165318;6648.772661158469;816.9509829410255;896.3187977137422;926.7312479380904;1001.1790331927895;1029.6859457865423;29393.559096613568;30112.84943575268;30476.54285185724;30948;30969.845150774552;992.270681049428;1028.1671845804922;1074.7313171707074;1074.785042991405;1106.1869525898458;15625.3749850007;16509.752353714703;16517.58706032359;16525.173741313127;16759;145248;146794;148125.25937370243;148530;148689;58183;58769;58857.70571147202;58933.41066589365;58957.705211473665;1217.8173274008793;1250.7435975624976;1267.9112462127562;1313.0939651640338;1313.967150821218;27755.167344978876;30944.226394340323;31273;31387.84306078455;31531.21171970719;48915.75542122267;49481.752591236815;49615.75192124161;49688.503114968385;49733.50266497289;356247;356430;356729;357035;357115;25219;25484.745152548236;25910.87044564765;27010.32474188161;27488.17535473939;41790.373144403464;41820.58179418289;41843.7907810459;41867.58132418759;42418.78790606027;35644.46533301951;35744.463833042006;36034.819825900704;36547;36980.78874619531;35764.64235357613;35919.102022449646;35934.85628153733;36567.45148822717;37245.25509489849;34719.82640086783;35344.82327588346;35495.46756798599;35500.822495887354;35906.8204658975;31146.688533115284;32266.83866580652;32469.188270292485;32722.672773271963;32958.50562241521;24082.277531674077;24493.632595511437;24731.929954358955;24762.428453597797;24933.413863778103 05:07:29 INFO - PROCESS | 4398 | |2;jquery.html;354.34623120342957;369.92601479704064;384.76721583442014;389.1710656302077;407.5354096330184;14200.502982395605;18380.17289221985;19068.809311906894;19363;20620.17519299226;17632.735508967377;18371.265149394007;18749.06254687267;19433.22267109314;19493.317733879318;5586.078297080987;5822.650640961535;5863.530917526609;5866.677332746697;5874.295084589855;5600.383957764642;5729.512991395736;5769.16347129666;5802.970985145068;5844.912326315109;5762.596618236725;5763.654180749148;5765.164051212571;5765.394633563478;5768.451997060293;5525.226468294442;5702.65784052956;5728.198052272685;5736.6558006519745;5766.481016708495;5134.332536770234;5134.794608215667;5140.511651393112;5144.382674079097;5144.588432925357;21956.89021554898;22375.77624223753;22646.77353226463;22715.886420567957;22899;2234.9553008939893;2276.3512398966213;2298.908043678251;2356.5286942611547;2414.903403863861;13557.661058473519;14181.361838717283;14450.710985780226;14485.42058317666;14696.926515367462;1903.5146037760283;1936.06101040995;1986.2452268138015;2026.3313106674761;2036.4297996560986;49907;68201.65899170522;73467.63266183688;74150.62924685396;76053.61973190153;9983.450910200008;10053.396796192106;10535.209859260585;10801.621943231949;10985.780284394268;13075.542356017493;13297.534586289434;13596.38816253271;14226.928865355814;14265;8024.398170137263;8186.631601577943;8208.261256486945;8430.831383372299;8439.746807595782;8004.719834805754;8027.678892844221;8078.272955433921;8250.711225107092;8295.751127466187;6479.740810367627;6496.805095847132;6515.641639709766;6579.144711187515;6650.434713049348;1827.972580411295;1864.2542982806985;1932.1112288347238;1934.7775005874223;1942.1260432805293;917.2799352508249;931.5714771205321;947.3842002698163;969.069693094637;1013.4024214040275;18214.63570728578;18829.081492368703;19702.60594788125;19732.40802775919;19775.802241977683;957.5164541906292;961.7739831139677;962.5331714118647;1023.4831410137981;1046.5499835070993;10008.299419040743;11201.71180314258;11258.43707814606;11270.774584508265;11291.774164516828;365105;385813;385860;389562;389645;59957.70021149866;78629.60685196538;80096.59951700205;82295;84927.15072849316;967.883853937532;989.7030890732751;994.7015895231401;1012.6405126180247;1027.1833892055834;16314;16703.415380461625;17201.827981720522;17347.913260433616;17648.55878602994;35517.289654207285;35824.46263306;36996.63003370039;37002.814985924895;38676.41985370166;47937;48698.26952595643;49400.30697083915;49488;50561.24158137557;7525.58609276495;7694.769156925301;7801.336886364723;7831.099423566135;8039.035315762146;8593.226609605166;11419.31484110956;12337.198082124632;12494.937525312314;12950.935245323713;10914.890851091388;10942.23404361687;11263.324200547993;11368.94315528417;11669.474873630877;10834.512446939749;11014.944925275322;11086;11198.328100314007;11203.663890083311;11207.551697931991;11351.091912647134;11507.597234096766;11588.594399195988;11982.281063136239;7668.654910528928;7819.569923654253;7868.8819667706175;7972;8032.07631122405;9572.721029530221;11024.55842306456;11158.274712143684;11250.66248012561;11380.658580242605 05:07:29 INFO - PROCESS | 4398 | |3;mootools.html;1309.3191540398998;1370.636781252968;1389.6595334143133;1462.378489142115;1664.093069277243;6894.379505844479;7625.275598818111;7882.251186137308;7889.329407000405;8044.195580441953;7852.8036799080055;7929.563873986928;7934.2065793420625;7937;7939.5633240171765;1849.6948003579394;1912.177763561675;2070.7204527388762;2107.673310636854;2107.8524503284775;2088.634488964433;2150.2366659835766;2153.289414493221;2153.5800518898823;2160.773118822525;2113.2392338758036;2128.382768996989;2128.4040468668795;2131.126238242321;2131.893405329727;2088.770235274126;2108.493961449241;2110.767815540297;2113.0174468872046;2113.5455876986366;2054.8869812160324;2065.865718728278;2076.6054449654666;2099.7060411542398;2104.0111147760526;8063.919360806375;8072.112067672551;8162.959185204096;8177.509349438993;8177.672893084269;4706.317583950308;4826.638002149853;4826.903461930741;4839.249916262985;4840.15297322967;2937.5593660950813;3080.7073328034026;3122.7657925655667;3159.0680749179046;3195.6325022622464;951.8143961927427;991.2070343724992;1007.8790545134558;1023.9590416383336;1070.978580428387;11228.606998755085;11295.039921606673;11306.943465282704;11307.321560706385;11318.320900745899;4001.979990100031;4306.246406878847;4322.8703138905885;4351.456067991501;4387.868363949086;9284.350095493252;9287.257019438428;9297.163255307054;9300.023497532797;9305.25557955362;4887.095887260797;4996.075725990703;5078.644494885397;5101.183810590288;5105.846824595268;4424.823007079745;4498.595126438636;4647.581717645428;4663.976680116578;4664.323673067454;3536.2397084626928;3596.1908570571413;3613.2050948791225;3640.9453858192146;3692.7230457715777;1108.9722756931142;1125.5554056147776;1131.7453572946185;1135.7047167736448;1137.7553825927466;832.76682528892;923.6259314977444;939.8919124300656;951.9524023798921;963.9228861691048;5248.580113590903;5324.201369794562;5483.177523371526;5618.550515958714;5702.315722113373;420.67818119138883;423.5552669696868;424.54149518519927;433.4019053705866;435.414367675476;3633.945490817587;3661.1945372018113;3664.358737221029;3694.852205911787;3832.80835958201;9729.270304727173;10177.389356638625;10182.319917213577;10188;10191.7452063699;9972.052654997764;10047.447390393452;10092.243081768896;10157.39055656663;10184.643537476153;7574.924250757532;7674.270944260286;7806.180351063056;7814.57019863913;7815.101263354673;5266.315379000705;5510.118381059011;5545.805896793593;5584.720763961867;5684.687342196219;9203.585838637144;9293.070692930653;9306.72079837606;9319.58061887203;9347.906520934703;9316.394434361744;9395.2953528487;9401.858972115288;9455.243580513405;9511.334206605472;5905.8014235158935;6387.968060159669;6739.932600674127;6790.490713196629;6933.583984960918;7724.652390642547;7733.149353571215;7793;7795.883061754192;7804.297613214838;6796.898046529207;6893.517453778188;6912.654367281716;7109.324614161646;7128.928710712827;6689.331066893272;6771.322867713189;6797.89803152963;6994.965025174842;7012.544184627983;6507.154069970874;6522.15212022434;6567.770128045496;6575.769848055295;6583.703733332108;4754.50077741846;4760.143174228534;4773.618110551078;4775.403074615673;4864.805407783649;4757.9048419032115;4810;4931.654784165074;4951.183054795918;4963.255511673278 05:07:29 INFO - PROCESS | 4398 | |4;prototype.html;359.4482469409457;364.0534610013964;365.3259735787472;373.3764613096131;374.5542804063163;5198.376194856618;5244.68531888087;5251.973740131299;5258.211268309756;5273.736313184345;4998.500149985;4999.450060493343;5141.331626888508;5182.041322355358;5188.92216616751;4128.3601041838565;4154.688398370135;4161.459010328653;4162.750234985895;4177.415161877339;4123.793810309473;4142.606452387018;4154.875353739378;4169.749815011109;4170.582941705836;3941.4481972523868;4008.1983603279314;4116.115035267417;4132.628063474287;4135.400366946791;4094.6110119538744;4101.1387608601935;4108.753474791507;4119.155573107506;4131.400946862718;3634.254977729559;3667.504886840269;3680.9263814723554;3692.5015122958325;3718.9442158367647;4762.476127625959;4982.476839931791;5013.47358527353;5031.195008798574;5095.210242412395;4551.771021824122;4562.520935301779;4581.518940511231;4592.563706447882;4596.770161491912;5441.428649991773;5451.936872309901;5459.890802183934;5472.370677372073;5477.1510415885605;3805.1058001369656;3882.9805850970847;3886.2227554489214;3896.5324161100566;3922.2743792398214;5526.640768350045;5596.888062238732;5610.410906854762;5621.184928185431;5622.775088996435;3980.2636512245326;3983.0440694233753;3984.621460961204;3985.6612187964347;4003.199360127986;3957.8416863325147;4020.7788571628257;4026.516817981861;4038.7980600970423;4049.7772622506045;4502.032063106448;4535.478419981744;4593.724376537419;4601.7699115044115;4622.537746225418;5340.385855626652;5373;5385.522659576561;5390.029794636925;5445.183222516575;1337.6388375138658;1342.6441992871896;1348.9460421583224;1352.6956434802091;1352.959411217665;895.8387490251687;923.4136323434667;928.3640706116332;930.2557953637198;940.4028441939417;5118.513741194581;5149;5159.17453207485;5162.5095615916425;5168.689878607295;5441.319835020622;5450.88256907341;5453.454654534515;5457.781688732485;5466;5039.420466646309;5058.8482345528955;5059.544640982329;5125.179971204664;5207.739613019334;2822.379076603144;2823.4776566335295;2835.3903910658896;2847.917791239357;2856.928576785597;5407.405185429599;5491.066518691832;5517.69652669107;5596.664200148004;5598.524125449382;5357.142857142839;5362.544183744348;5417.458254174551;5464.672119672832;5478.890422191614;5550.834324791758;5572.944270557324;5574.888502229933;5644.8871022579315;5647.830565083054;5289.57683385336;5309.150535914235;5319.25530425733;5499.697516636624;5523.972380138073;5230.561595561251;5294.364676238876;5335.306410166809;5345.518903298722;5369.6509726867625;5560.972195138998;5564.860878478071;5593.356763972195;5610.887782244414;5645.774169033357;3707.888763337104;3815.332316844597;3817.9809100954317;3838.4626152338137;3910.966297525214;5284.550813180769;5294.814681486129;5298.735063246745;5338.759755811076;5437.728113594384;5392.487713667196;5473.01485732556;5486.341639003345;5500.752466138953;5520.585956053232;5441.401445841034;5490.615656903979;5504.8253343783645;5513.476219759116;5522.889542209214;5215.713135777569;5364.38309594386;5409.648372855751;5424.430434804289;5481.232627432083;5397.190421436816;5430.67415955044;5448.7548060336585;5503.504684578407;5505;5284.26020357158;5294.788208471772;5318.308619879391;5425.701586412785;5433.945660543344 05:07:29 INFO - PROCESS | 4398 | |5;yui.html;714.6819665248963;720.3480849830903;738.4387865222433;748.9251074892511;788.2905385153365;14619.269036548183;15775.84224157758;15792;15803.92098039511;15804.841951580494;13430.798538021938;14748.26258687064;14808;14915.850841491581;14925.328360223788;777.2344240922687;803.0684406088909;809.2352726673296;809.4414853750923;809.5830647216688;803.5098589860181;805.7018903005896;806.6410447350931;806.9072056713466;807.0839597057324;844.0968164064453;844.3287586368821;846.289117141601;848.7326492154982;849.4223927729141;840.0339609449121;842.1368097699846;842.3976856547567;842.7766641839949;845.7420486751538;609.6037575575867;611.6758118197363;617.5491890919653;618.4495798739081;618.4742968476804;13779.724405511834;13804.17174969505;14979.625509362244;15873.92063039689;16125.193740313054;14785.778213326701;14963.476278330316;15386.384544618202;15430.845691543052;15575.532734017997;11821.408929553489;11977.023791074462;12244.571439999647;12459.937700311531;12476.18904771196;3586.1393265616066;3625.6011838697723;3679.172186258097;3687.7602955808056;3727.310447567209;44429.55570444319;50639;51192;57211.71394143044;75418.24581754167;11584.536618535165;12000.159988800875;12164.939175304067;12507.874921250672;12889.742205155844;22145.557088858455;22363.88818055932;22401;22554.436139096324;22588.096476141098;8205.261526462527;8424.410291279673;8439.95780021096;8454.915450845412;8555.700550480704;4401.449818772653;4435.534268901719;4485.551444855489;4493.662975276868;4531.45622525299;3395.151212196951;3508.9298214035575;3669.3578623740736;3799.7340186186702;3804.4863943367845;620.7485968182893;623.7598524568074;624.1355722324613;627.4729227448964;631.8009826904511;633.6134957675814;671.214678825775;673.8955461903413;682.1950098883365;706.1385110165589;9143.542822858963;9149.268058555299;9473.431594104375;9696.17582505495;9789.804203915883;652.3346186889441;692.5117791956659;699.488300462022;717.2504732554389;725.5429079679874;4567.840125595655;4663.27719203524;4678.953210467852;4685.81256749733;4706.458757242893;77550;80691;80792;81384;81893;57038.42961570414;58644.70677646584;70001.2999870005;70105;70919;55227.72386138044;58745.412545873995;59335;60561;60780.392196078355;9248.121428464285;9311.314474220217;9439.386439881386;9552.474613896302;9644.855327170239;41133.58866411298;41585.37621935734;42263.57736422597;42852.286248513265;42852.57147428486;52527;52947.73526132345;55530;56514.15228771654;58238.4176158233;12621.684457888627;12862.163959342613;12918.935405322913;13024.046393041119;13156.342182890625;13888.097273677178;14387.352569134624;14614.85385146135;14882.032667876554;15550.611234719223;12168.939155304166;12608.621741347773;12754;13536.661583460098;13957.302134893418;11196.272242304223;11242.269252498889;11462.254953428;11645.48608680866;11710.882891170979;12507.499700011898;12703.809442858179;12991.220526768424;13096.869031309565;13967.161970281815;9792.363496372713;10312.071913527818;10462.63380781669;10562.207834412297;11132.721681958015;10561.41912194806;11936.641900742992;12065.457054432747;12083.879161208628;12087.39563021863 05:07:29 INFO - PROCESS | 4398 | __end_tp_report 05:07:29 INFO - PROCESS | 4398 | __start_cc_report 05:07:29 INFO - PROCESS | 4398 | _x_x_mozilla_cycle_collect,1394 05:07:29 INFO - PROCESS | 4398 | __end_cc_report 05:07:29 INFO - PROCESS | 4398 | __startTimestamp1481375249504__endTimestamp 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | ------- Summary: start ------- 05:07:29 INFO - PROCESS | 4398 | Number of tests: 6 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#0] dojo.html Cycles:165 Average:5317.66 Median:5533.17 stddev:563.48 (10.2%) stddev-sans-first:541.47 05:07:29 INFO - PROCESS | 4398 | Values: 3254.9 3286.3 3288.9 3313.4 3337.5 5209.7 5271.2 5298.8 5360.3 5387.4 5268.8 5282.7 5284.4 5284.5 5291.6 4762.0 4780.9 4794.6 4818.3 4837.7 4868.0 4938.6 4945.7 4946.2 4952.3 4860.1 4904.4 4909.8 4930.0 4946.1 4893.1 4935.6 4960.7 4962.3 4967.1 4709.4 4746.2 4785.4 4817.9 4855.1 5314.4 5317.1 5331.3 5351.9 5373.7 5670.1 5721.4 5722.1 5725.4 5728.5 5581.7 5634.7 5657.8 5710.8 5795.8 5319.9 5345.3 5346.9 5347.3 5347.3 5783.9 5797.8 5805.2 5836.7 5860.2 5606.0 5660.2 5662.0 5663.9 5700.9 5707.5 5757.5 5759.0 5762.6 5765.6 5575.2 5625.3 5627.4 5686.5 5707.6 5678.3 5685.4 5686.8 5688.9 5693.7 4387.9 4421.4 4424.3 4427.6 4428.5 4029.6 4044.5 4044.6 4046.5 4048.1 5409.7 5411.7 5417.8 5422.3 5424.5 5667.1 5681.9 5697.5 5698.4 5699.7 5364.9 5366.0 5367.9 5372.3 5386.2 4892.8 4899.5 4904.8 4905.9 4951.3 5677.2 5700.6 5798.9 5917.1 5926.5 5606.7 5710.0 5732.0 5739.5 5816.5 5926.3 5926.7 5930.3 5930.9 5972.8 5661.3 5676.5 5680.0 5681.5 5682.5 5612.9 5658.4 5672.2 5691.1 5756.8 5619.1 5622.8 5629.8 5643.0 5668.5 5592.3 5595.2 5598.7 5600.5 5600.6 5368.6 5432.8 5527.2 5586.7 5592.2 5566.3 5657.1 5715.1 5723.4 5725.1 5521.4 5526.8 5529.5 5531.8 5533.2 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#1] ext.html Cycles:180 Average:45581.37 Median:348433.50 stddev:79220.23 (22.7%) stddev-sans-first:79370.31 05:07:29 INFO - PROCESS | 4398 | Values: 549.6 551.6 576.6 579.8 589.0 31335.7 39448.8 40949.4 40977.4 44904.1 43252.4 43749.8 43790.3 43843.6 43874.3 8471.7 8662.2 8668.0 8673.1 8674.5 7970.7 8178.3 10024.9 10290.6 10292.9 10235.3 10303.9 10306.4 10307.3 10307.6 10214.2 10271.2 10272.5 10274.7 10297.2 10136.8 10214.1 10214.4 10215.8 10217.8 41178.4 41471.2 41476.4 41486.0 41490.4 3340.7 3413.9 3427.6 3636.9 4047.7 28413.0 28627.0 29142.0 31297.2 31518.7 4025.7 4046.4 4117.4 4145.8 4155.1 347701.0 347890.0 348977.0 349511.0 350112.0 50684.5 50795.0 50896.2 50963.4 50984.9 53576.0 53603.7 53886.5 53920.5 53922.5 15190.9 15842.5 15852.1 15859.6 15860.3 10904.6 10972.1 10976.8 11021.1 11468.2 16260.6 16501.9 16618.3 17099.8 17163.7 6392.2 6412.2 6546.6 6614.0 6648.8 817.0 896.3 926.7 1001.2 1029.7 29393.6 30112.8 30476.5 30948.0 30969.8 992.3 1028.2 1074.7 1074.8 1106.2 15625.4 16509.8 16517.6 16525.2 16759.0 145248.0 146794.0 148125.3 148530.0 148689.0 58183.0 58769.0 58857.7 58933.4 58957.7 1217.8 1250.7 1267.9 1313.1 1314.0 27755.2 30944.2 31273.0 31387.8 31531.2 48915.8 49481.8 49615.8 49688.5 49733.5 356247.0 356430.0 356729.0 357035.0 357115.0 25219.0 25484.7 25910.9 27010.3 27488.2 41790.4 41820.6 41843.8 41867.6 42418.8 35644.5 35744.5 36034.8 36547.0 36980.8 35764.6 35919.1 35934.9 36567.5 37245.3 34719.8 35344.8 35495.5 35500.8 35906.8 31146.7 32266.8 32469.2 32722.7 32958.5 24082.3 24493.6 24731.9 24762.4 24933.4 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#2] jquery.html Cycles:180 Average:24794.20 Median:182737.46 stddev:63194.13 (34.6%) stddev-sans-first:63344.76 05:07:29 INFO - PROCESS | 4398 | Values: 354.3 369.9 384.8 389.2 407.5 14200.5 18380.2 19068.8 19363.0 20620.2 17632.7 18371.3 18749.1 19433.2 19493.3 5586.1 5822.7 5863.5 5866.7 5874.3 5600.4 5729.5 5769.2 5803.0 5844.9 5762.6 5763.7 5765.2 5765.4 5768.5 5525.2 5702.7 5728.2 5736.7 5766.5 5134.3 5134.8 5140.5 5144.4 5144.6 21956.9 22375.8 22646.8 22715.9 22899.0 2235.0 2276.4 2298.9 2356.5 2414.9 13557.7 14181.4 14450.7 14485.4 14696.9 1903.5 1936.1 1986.2 2026.3 2036.4 49907.0 68201.7 73467.6 74150.6 76053.6 9983.5 10053.4 10535.2 10801.6 10985.8 13075.5 13297.5 13596.4 14226.9 14265.0 8024.4 8186.6 8208.3 8430.8 8439.7 8004.7 8027.7 8078.3 8250.7 8295.8 6479.7 6496.8 6515.6 6579.1 6650.4 1828.0 1864.3 1932.1 1934.8 1942.1 917.3 931.6 947.4 969.1 1013.4 18214.6 18829.1 19702.6 19732.4 19775.8 957.5 961.8 962.5 1023.5 1046.5 10008.3 11201.7 11258.4 11270.8 11291.8 365105.0 385813.0 385860.0 389562.0 389645.0 59957.7 78629.6 80096.6 82295.0 84927.2 967.9 989.7 994.7 1012.6 1027.2 16314.0 16703.4 17201.8 17347.9 17648.6 35517.3 35824.5 36996.6 37002.8 38676.4 47937.0 48698.3 49400.3 49488.0 50561.2 7525.6 7694.8 7801.3 7831.1 8039.0 8593.2 11419.3 12337.2 12494.9 12950.9 10914.9 10942.2 11263.3 11368.9 11669.5 10834.5 11014.9 11086.0 11198.3 11203.7 11207.6 11351.1 11507.6 11588.6 11982.3 7668.7 7819.6 7868.9 7972.0 8032.1 9572.7 11024.6 11158.3 11250.7 11380.7 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#3] mootools.html Cycles:180 Average:5299.72 Median:4839.70 stddev:3035.08 (62.7%) stddev-sans-first:3028.78 05:07:29 INFO - PROCESS | 4398 | Values: 1309.3 1370.6 1389.7 1462.4 1664.1 6894.4 7625.3 7882.3 7889.3 8044.2 7852.8 7929.6 7934.2 7937.0 7939.6 1849.7 1912.2 2070.7 2107.7 2107.9 2088.6 2150.2 2153.3 2153.6 2160.8 2113.2 2128.4 2128.4 2131.1 2131.9 2088.8 2108.5 2110.8 2113.0 2113.5 2054.9 2065.9 2076.6 2099.7 2104.0 8063.9 8072.1 8163.0 8177.5 8177.7 4706.3 4826.6 4826.9 4839.2 4840.2 2937.6 3080.7 3122.8 3159.1 3195.6 951.8 991.2 1007.9 1024.0 1071.0 11228.6 11295.0 11306.9 11307.3 11318.3 4002.0 4306.2 4322.9 4351.5 4387.9 9284.4 9287.3 9297.2 9300.0 9305.3 4887.1 4996.1 5078.6 5101.2 5105.8 4424.8 4498.6 4647.6 4664.0 4664.3 3536.2 3596.2 3613.2 3640.9 3692.7 1109.0 1125.6 1131.7 1135.7 1137.8 832.8 923.6 939.9 952.0 963.9 5248.6 5324.2 5483.2 5618.6 5702.3 420.7 423.6 424.5 433.4 435.4 3633.9 3661.2 3664.4 3694.9 3832.8 9729.3 10177.4 10182.3 10188.0 10191.7 9972.1 10047.4 10092.2 10157.4 10184.6 7574.9 7674.3 7806.2 7814.6 7815.1 5266.3 5510.1 5545.8 5584.7 5684.7 9203.6 9293.1 9306.7 9319.6 9347.9 9316.4 9395.3 9401.9 9455.2 9511.3 5905.8 6388.0 6739.9 6790.5 6933.6 7724.7 7733.1 7793.0 7795.9 7804.3 6796.9 6893.5 6912.7 7109.3 7128.9 6689.3 6771.3 6797.9 6995.0 7012.5 6507.2 6522.2 6567.8 6575.8 6583.7 4754.5 4760.1 4773.6 4775.4 4864.8 4757.9 4810.0 4931.7 4951.2 4963.3 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#4] prototype.html Cycles:180 Average:4535.72 Median:5211.73 stddev:1313.44 (25.2%) stddev-sans-first:1279.18 05:07:29 INFO - PROCESS | 4398 | Values: 359.4 364.1 365.3 373.4 374.6 5198.4 5244.7 5252.0 5258.2 5273.7 4998.5 4999.5 5141.3 5182.0 5188.9 4128.4 4154.7 4161.5 4162.8 4177.4 4123.8 4142.6 4154.9 4169.7 4170.6 3941.4 4008.2 4116.1 4132.6 4135.4 4094.6 4101.1 4108.8 4119.2 4131.4 3634.3 3667.5 3680.9 3692.5 3718.9 4762.5 4982.5 5013.5 5031.2 5095.2 4551.8 4562.5 4581.5 4592.6 4596.8 5441.4 5451.9 5459.9 5472.4 5477.2 3805.1 3883.0 3886.2 3896.5 3922.3 5526.6 5596.9 5610.4 5621.2 5622.8 3980.3 3983.0 3984.6 3985.7 4003.2 3957.8 4020.8 4026.5 4038.8 4049.8 4502.0 4535.5 4593.7 4601.8 4622.5 5340.4 5373.0 5385.5 5390.0 5445.2 1337.6 1342.6 1348.9 1352.7 1353.0 895.8 923.4 928.4 930.3 940.4 5118.5 5149.0 5159.2 5162.5 5168.7 5441.3 5450.9 5453.5 5457.8 5466.0 5039.4 5058.8 5059.5 5125.2 5207.7 2822.4 2823.5 2835.4 2847.9 2856.9 5407.4 5491.1 5517.7 5596.7 5598.5 5357.1 5362.5 5417.5 5464.7 5478.9 5550.8 5572.9 5574.9 5644.9 5647.8 5289.6 5309.2 5319.3 5499.7 5524.0 5230.6 5294.4 5335.3 5345.5 5369.7 5561.0 5564.9 5593.4 5610.9 5645.8 3707.9 3815.3 3818.0 3838.5 3911.0 5284.6 5294.8 5298.7 5338.8 5437.7 5392.5 5473.0 5486.3 5500.8 5520.6 5441.4 5490.6 5504.8 5513.5 5522.9 5215.7 5364.4 5409.6 5424.4 5481.2 5397.2 5430.7 5448.8 5503.5 5505.0 5284.3 5294.8 5318.3 5425.7 5433.9 05:07:29 INFO - PROCESS | 4398 | 05:07:29 INFO - PROCESS | 4398 | [#5] yui.html Cycles:180 Average:16738.21 Median:4615.56 stddev:20710.79 (448.7%) stddev-sans-first:20733.94 05:07:29 INFO - PROCESS | 4398 | Values: 714.7 720.3 738.4 748.9 788.3 14619.3 15775.8 15792.0 15803.9 15804.8 13430.8 14748.3 14808.0 14915.9 14925.3 777.2 803.1 809.2 809.4 809.6 803.5 805.7 806.6 806.9 807.1 844.1 844.3 846.3 848.7 849.4 840.0 842.1 842.4 842.8 845.7 609.6 611.7 617.5 618.4 618.5 13779.7 13804.2 14979.6 15873.9 16125.2 14785.8 14963.5 15386.4 15430.8 15575.5 11821.4 11977.0 12244.6 12459.9 12476.2 3586.1 3625.6 3679.2 3687.8 3727.3 44429.6 50639.0 51192.0 57211.7 75418.2 11584.5 12000.2 12164.9 12507.9 12889.7 22145.6 22363.9 22401.0 22554.4 22588.1 8205.3 8424.4 8440.0 8454.9 8555.7 4401.4 4435.5 4485.6 4493.7 4531.5 3395.2 3508.9 3669.4 3799.7 3804.5 620.7 623.8 624.1 627.5 631.8 633.6 671.2 673.9 682.2 706.1 9143.5 9149.3 9473.4 9696.2 9789.8 652.3 692.5 699.5 717.3 725.5 4567.8 4663.3 4679.0 4685.8 4706.5 77550.0 80691.0 80792.0 81384.0 81893.0 57038.4 58644.7 70001.3 70105.0 70919.0 55227.7 58745.4 59335.0 60561.0 60780.4 9248.1 9311.3 9439.4 9552.5 9644.9 41133.6 41585.4 42263.6 42852.3 42852.6 52527.0 52947.7 55530.0 56514.2 58238.4 12621.7 12862.2 12918.9 13024.0 13156.3 13888.1 14387.4 14614.9 14882.0 15550.6 12168.9 12608.6 12754.0 13536.7 13957.3 11196.3 11242.3 11462.3 11645.5 11710.9 12507.5 12703.8 12991.2 13096.9 13967.2 9792.4 10312.1 10462.6 10562.2 11132.7 10561.4 11936.6 12065.5 12083.9 12087.4 05:07:29 INFO - PROCESS | 4398 | -------- Summary: end -------- 05:07:29 INFO - PROCESS | 4398 | 05:07:30 INFO - TEST-INFO | 4398: exit 0 05:07:30 INFO - TEST-OK | dromaeo_css | took 1118216ms 05:07:30 INFO - TEST-START | kraken 05:07:30 INFO - operating with platform_type : linux_ 05:07:30 INFO - Initialising browser for kraken test... 05:07:30 INFO - TEST-INFO | started process 4702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1kpVSN/profile http://localhost:39729/getInfo.html) 05:07:34 INFO - PROCESS | 4702 | __metrics Screen width/height:1600/1200 05:07:34 INFO - PROCESS | 4702 | colorDepth:24 05:07:34 INFO - PROCESS | 4702 | Browser inner width/height: 1024/697 05:07:34 INFO - PROCESS | 4702 | __metrics 05:07:35 INFO - PROCESS | 4702 | 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" 05:07:35 INFO - PROCESS | 4702 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:36 INFO - PROCESS | 4702 | console.error: PushService: 05:07:36 INFO - PROCESS | 4702 | stateChangeProcessEnqueue: Error transitioning state 05:07:36 INFO - PROCESS | 4702 | UnknownError 05:07:36 INFO - TEST-INFO | 4702: exit 0 05:07:36 INFO - Browser initialized. 05:07:36 INFO - Running cycle 1/1 for kraken test... 05:07:36 INFO - TEST-INFO | started process 4786 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1kpVSN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:48 INFO - PROCESS | 4786 | RSS: Main: 171999232 05:07:48 INFO - PROCESS | 4786 | 05:07:51 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39729/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:52 INFO - PROCESS | 4786 | RSS: Main: 234438656 05:07:52 INFO - PROCESS | 4786 | 05:07:54 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39729/tests/kraken/driver.html?testName=audio-dft) 05:07:55 INFO - PROCESS | 4786 | RSS: Main: 216670208 05:07:55 INFO - PROCESS | 4786 | 05:07:58 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39729/tests/kraken/driver.html?testName=audio-fft) 05:07:59 INFO - PROCESS | 4786 | RSS: Main: 298819584 05:07:59 INFO - PROCESS | 4786 | 05:08:01 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39729/tests/kraken/driver.html?testName=audio-oscillator) 05:08:01 INFO - PROCESS | 4786 | RSS: Main: 210247680 05:08:01 INFO - PROCESS | 4786 | 05:08:04 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39729/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:08:04 INFO - PROCESS | 4786 | RSS: Main: 212856832 05:08:04 INFO - PROCESS | 4786 | 05:08:07 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39729/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:08 INFO - PROCESS | 4786 | RSS: Main: 252387328 05:08:08 INFO - PROCESS | 4786 | 05:08:11 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39729/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:12 INFO - PROCESS | 4786 | RSS: Main: 252305408 05:08:12 INFO - PROCESS | 4786 | 05:08:15 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39729/tests/kraken/driver.html?testName=json-parse-financial) 05:08:15 INFO - PROCESS | 4786 | RSS: Main: 251162624 05:08:15 INFO - PROCESS | 4786 | 05:08:17 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39729/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:17 INFO - PROCESS | 4786 | RSS: Main: 213540864 05:08:17 INFO - PROCESS | 4786 | 05:08:19 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:20 INFO - PROCESS | 4786 | RSS: Main: 219803648 05:08:20 INFO - PROCESS | 4786 | 05:08:22 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:22 INFO - PROCESS | 4786 | RSS: Main: 224948224 05:08:22 INFO - PROCESS | 4786 | 05:08:25 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:26 INFO - PROCESS | 4786 | RSS: Main: 223834112 05:08:26 INFO - PROCESS | 4786 | 05:08:29 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:29 INFO - PROCESS | 4786 | RSS: Main: 227704832 05:08:29 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | Cycle 1(1): loaded http://localhost:39729/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39729/tests/kraken/driver.html?testName=ai-astar) 05:08:31 INFO - PROCESS | 4786 | RSS: Main: 279666688 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | __start_tp_report 05:08:31 INFO - PROCESS | 4786 | _x_x_mozilla_page_load 05:08:31 INFO - PROCESS | 4786 | _x_x_mozilla_page_load_details 05:08:31 INFO - PROCESS | 4786 | |i|pagename|runs| 05:08:31 INFO - PROCESS | 4786 | |0;ai-astar;110;112;113;113;113;111;113;111;113;113 05:08:31 INFO - PROCESS | 4786 | |1;audio-beat-detection;145;124;124;174;125;125;127;123;149;125 05:08:31 INFO - PROCESS | 4786 | |2;audio-dft;161;163;161;159;162;164;163;163;164;163 05:08:31 INFO - PROCESS | 4786 | |3;audio-fft;74;76;77;113;78;80;76;76;102;76 05:08:31 INFO - PROCESS | 4786 | |4;audio-oscillator;84;84;104;84;84;84;99;84;85;102 05:08:31 INFO - PROCESS | 4786 | |5;imaging-gaussian-blur;126;127;127;127;127;127;127;127;127;127 05:08:31 INFO - PROCESS | 4786 | |6;imaging-darkroom;147;148;146;145;146;146;147;147;146;146 05:08:31 INFO - PROCESS | 4786 | |7;imaging-desaturate;95;94;95;94;95;95;95;96;95;95 05:08:31 INFO - PROCESS | 4786 | |8;json-parse-financial;52;52;52;53;55;53;53;53;54;53 05:08:31 INFO - PROCESS | 4786 | |9;json-stringify-tinderbox;65;66;65;64;65;66;64;65;64;65 05:08:31 INFO - PROCESS | 4786 | |10;stanford-crypto-aes;85;85;86;85;86;85;93;94;132;83 05:08:31 INFO - PROCESS | 4786 | |11;stanford-crypto-ccm;140;134;131;140;131;130;130;134;149;132 05:08:31 INFO - PROCESS | 4786 | |12;stanford-crypto-pbkdf2;168;170;171;181;170;172;169;173;169;174 05:08:31 INFO - PROCESS | 4786 | |13;stanford-crypto-sha256-iterative;67;65;66;67;65;67;67;67;65;64 05:08:31 INFO - PROCESS | 4786 | __end_tp_report 05:08:31 INFO - PROCESS | 4786 | __start_cc_report 05:08:31 INFO - PROCESS | 4786 | _x_x_mozilla_cycle_collect,1644 05:08:31 INFO - PROCESS | 4786 | __end_cc_report 05:08:31 INFO - PROCESS | 4786 | __startTimestamp1481375311671__endTimestamp 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | ------- Summary: start ------- 05:08:31 INFO - PROCESS | 4786 | Number of tests: 14 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:1.14 (1.0%) stddev-sans-first:0.88 05:08:31 INFO - PROCESS | 4786 | Values: 110.0 112.0 113.0 113.0 113.0 111.0 113.0 111.0 113.0 113.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:126.00 stddev:16.86 (13.4%) stddev-sans-first:17.42 05:08:31 INFO - PROCESS | 4786 | Values: 145.0 124.0 124.0 174.0 125.0 125.0 127.0 123.0 149.0 125.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#2] audio-dft Cycles:10 Average:162.30 Median:163.00 stddev:1.57 (1.0%) stddev-sans-first:1.59 05:08:31 INFO - PROCESS | 4786 | Values: 161.0 163.0 161.0 159.0 162.0 164.0 163.0 163.0 164.0 163.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.00 stddev:13.36 (17.6%) stddev-sans-first:13.79 05:08:31 INFO - PROCESS | 4786 | Values: 74.0 76.0 77.0 113.0 78.0 80.0 76.0 76.0 102.0 76.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:84.00 stddev:8.55 (10.2%) stddev-sans-first:8.85 05:08:31 INFO - PROCESS | 4786 | Values: 84.0 84.0 104.0 84.0 84.0 84.0 99.0 84.0 85.0 102.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#5] imaging-gaussian-blur Cycles:10 Average:126.90 Median:127.00 stddev:0.32 (0.2%) stddev-sans-first:0.00 05:08:31 INFO - PROCESS | 4786 | Values: 126.0 127.0 127.0 127.0 127.0 127.0 127.0 127.0 127.0 127.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 05:08:31 INFO - PROCESS | 4786 | Values: 147.0 148.0 146.0 145.0 146.0 146.0 147.0 147.0 146.0 146.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:08:31 INFO - PROCESS | 4786 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 95.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.94 (1.8%) stddev-sans-first:0.93 05:08:31 INFO - PROCESS | 4786 | Values: 52.0 52.0 52.0 53.0 55.0 53.0 53.0 53.0 54.0 53.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 05:08:31 INFO - PROCESS | 4786 | Values: 65.0 66.0 65.0 64.0 65.0 66.0 64.0 65.0 64.0 65.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:85.50 stddev:14.72 (17.2%) stddev-sans-first:15.43 05:08:31 INFO - PROCESS | 4786 | Values: 85.0 85.0 86.0 85.0 86.0 85.0 93.0 94.0 132.0 83.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#11] stanford-crypto-ccm Cycles:10 Average:135.10 Median:134.00 stddev:6.14 (4.6%) stddev-sans-first:6.25 05:08:31 INFO - PROCESS | 4786 | Values: 140.0 134.0 131.0 140.0 131.0 130.0 130.0 134.0 149.0 132.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:171.50 stddev:3.77 (2.2%) stddev-sans-first:3.76 05:08:31 INFO - PROCESS | 4786 | Values: 168.0 170.0 171.0 181.0 170.0 172.0 169.0 173.0 169.0 174.0 05:08:31 INFO - PROCESS | 4786 | 05:08:31 INFO - PROCESS | 4786 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 05:08:31 INFO - PROCESS | 4786 | Values: 67.0 65.0 66.0 67.0 65.0 67.0 67.0 67.0 65.0 64.0 05:08:31 INFO - PROCESS | 4786 | -------- Summary: end -------- 05:08:31 INFO - PROCESS | 4786 | 05:08:32 INFO - TEST-INFO | 4786: exit 0 05:08:32 INFO - TEST-OK | kraken | took 62408ms 05:08:32 INFO - SUITE-END | took 1180s 05:08:33 INFO - Completed test suite (00:19:41) 05:08:33 INFO - javascript benchmark 05:08:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.9023529294122, 3286.326303107862, 3288.917777055574, 3313.370459612675, 3337.5494308268376, 5209.687418754877, 5271.15661494161, 5298.814541491044, 5360.27636269104, 5387.4073851876265, 5268.762905669245, 5282.656627319221, 5284.365876094874, 5284.471552844713, 5291.629585928987, 4762.047590481909, 4780.90438191236, 4794.640401969867, 4818.253170758538, 4837.7339246341435, 4868.026708821006, 4938.629602779788, 4945.703257804544, 4946.208606622941, 4952.331435256248, 4860.1494738420815, 4904.386951631046, 4909.8036078557, 4930.0, 4946.1344264753525, 4893.09477746618, 4935.629827762897, 4960.677555958851, 4962.305277261186, 4967.081089998362, 4709.411323584552, 4746.21687421575, 4785.353977213066, 4817.879553011203, 4855.126077306083, 5314.388845282765, 5317.12267475866, 5331.306930099101, 5351.892962140735, 5373.704446255454, 5670.121131224667, 5721.427857214287, 5722.084466485384, 5725.427457254283, 5728.518057285184, 5581.748821303051, 5634.690092044936, 5657.830265092054, 5710.8001219957105, 5795.82612521625, 5319.9202011969855, 5345.278387417688, 5346.893062138735, 5347.304850369427, 5347.331583552056, 5783.913241301384, 5797.768089276466, 5805.216295800097, 5836.737346819404, 5860.238169037998, 5605.971970140124, 5660.150977353348, 5661.971690141523, 5663.915041274384, 5700.857478562986, 5707.4577915098, 5757.453041961007, 5758.971205144031, 5762.625429347163, 5765.567582431335, 5575.219469274305, 5625.324961004706, 5627.43725627434, 5686.488216060492, 5707.571932105108, 5678.346990096109, 5685.431456854364, 5686.77252909879, 5688.914666280009, 5693.715314234271, 4387.912241755147, 4421.358902959053, 4424.3142312941545, 4427.579379958963, 4428.535003824552, 4029.576894426043, 4044.4944381952255, 4044.6359827615656, 4046.5144182698264, 4048.1296521248073, 5409.702466364309, 5411.729413529308, 5417.83746487606, 5422.349318081855, 5424.484673955968, 5667.149927510906, 5681.857953551112, 5697.544196464273, 5698.430156984268, 5699.74351154199, 5364.892702146013, 5365.973170134124, 5367.946320536745, 5372.274742909735, 5386.192071189353, 4892.804287828518, 4899.461059283476, 4904.779284932187, 4905.852824415273, 4951.282064100757, 5677.205191273142, 5700.572457065819, 5798.94201057984, 5917.112433135064, 5926.466618004314, 5606.719664016701, 5710.0, 5732.0, 5739.454751798572, 5816.534677225727, 5926.288845338587, 5926.703664816656, 5930.318013428511, 5930.881382372242, 5972.820815375546, 5661.264035675335, 5676.545876329801, 5679.971600141973, 5681.545476361964, 5682.516986056232, 5612.85967850807, 5658.405867383865, 5672.234248376416, 5691.060974939254, 5756.827295181151, 5619.100943849128, 5622.7750889963945, 5629.774809007594, 5643.0, 5668.5465162787705, 5592.273004509388, 5595.188697638738, 5598.664080155204, 5600.523955463831, 5600.551955843604, 5368.570514358764, 5432.809851655331, 5527.198556209408, 5586.692731899622, 5592.161175823659, 5566.276384070045, 5657.094864821609, 5715.142728590745, 5723.399043100415, 5725.08398656213, 5521.365043019943, 5526.806561770319, 5529.502344788988, 5531.751071201886, 5533.170024496358], "value": 5283.054055629065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.5987928811969, 551.6497024389513, 576.5963825322275, 579.7680927628948, 588.9676067816268, 31335.686643133562, 39448.80275598622, 40949.3857592136, 40977.38533921994, 44904.10191796162, 43252.3512147318, 43749.78125109378, 43790.34314485277, 43843.56156438443, 43874.341884871676, 8471.703490377853, 8662.220400163984, 8668.046514883357, 8673.13268673134, 8674.479531228115, 7970.721024764134, 8178.345732341428, 10024.89975100247, 10290.639827606035, 10292.948535257314, 10235.334703244302, 10303.948480257588, 10306.381617102998, 10307.278490505667, 10307.63923262686, 10214.182865370753, 10271.178305735522, 10272.537735801907, 10274.7431314217, 10297.227707921937, 10136.79726405475, 10214.080732734015, 10214.438205898672, 10215.846762298572, 10217.795644087151, 41178.38232426516, 41471.1705765886, 41476.37785433221, 41486.0, 41490.377644335356, 3340.682635149657, 3413.8975830725117, 3427.5544179256785, 3636.890893273206, 4047.655949244318, 28413.0, 28627.0, 29142.0, 31297.217569560715, 31518.684813151805, 4025.7383270087644, 4046.433499310099, 4117.382392641128, 4145.834166633331, 4155.085881106152, 347701.0, 347890.0, 348977.0, 349511.0, 350112.0, 50684.49315506872, 50795.0, 50896.23655645168, 50963.432559720466, 50984.92120630341, 53576.0, 53603.73198133984, 53886.46113538893, 53920.46079539155, 53922.460775391744, 15190.924045379703, 15842.52472425806, 15852.1281329528, 15859.572638462363, 15860.286287117107, 10904.61833835812, 10972.122230221563, 10976.835347469794, 11021.118310535137, 11468.197226194254, 16260.593485162966, 16501.91749041247, 16618.25217865199, 17099.829001710073, 17163.656726865393, 6392.155490806639, 6412.23053233615, 6546.607203567801, 6613.966930165318, 6648.772661158469, 816.9509829410255, 896.3187977137422, 926.7312479380904, 1001.1790331927895, 1029.6859457865423, 29393.559096613568, 30112.84943575268, 30476.54285185724, 30948.0, 30969.845150774552, 992.270681049428, 1028.1671845804922, 1074.7313171707074, 1074.785042991405, 1106.1869525898458, 15625.3749850007, 16509.752353714703, 16517.58706032359, 16525.173741313127, 16759.0, 145248.0, 146794.0, 148125.25937370243, 148530.0, 148689.0, 58183.0, 58769.0, 58857.70571147202, 58933.41066589365, 58957.705211473665, 1217.8173274008793, 1250.7435975624976, 1267.9112462127562, 1313.0939651640338, 1313.967150821218, 27755.167344978876, 30944.226394340323, 31273.0, 31387.84306078455, 31531.21171970719, 48915.75542122267, 49481.752591236815, 49615.75192124161, 49688.503114968385, 49733.50266497289, 356247.0, 356430.0, 356729.0, 357035.0, 357115.0, 25219.0, 25484.745152548236, 25910.87044564765, 27010.32474188161, 27488.17535473939, 41790.373144403464, 41820.58179418289, 41843.7907810459, 41867.58132418759, 42418.78790606027, 35644.46533301951, 35744.463833042006, 36034.819825900704, 36547.0, 36980.78874619531, 35764.64235357613, 35919.102022449646, 35934.85628153733, 36567.45148822717, 37245.25509489849, 34719.82640086783, 35344.82327588346, 35495.46756798599, 35500.822495887354, 35906.8204658975, 31146.688533115284, 32266.83866580652, 32469.188270292485, 32722.672773271963, 32958.50562241521, 24082.277531674077, 24493.632595511437, 24731.929954358955, 24762.428453597797, 24933.413863778103], "value": 18388.594311096887, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.34623120342957, 369.92601479704064, 384.76721583442014, 389.1710656302077, 407.5354096330184, 14200.502982395605, 18380.17289221985, 19068.809311906894, 19363.0, 20620.17519299226, 17632.735508967377, 18371.265149394007, 18749.06254687267, 19433.22267109314, 19493.317733879318, 5586.078297080987, 5822.650640961535, 5863.530917526609, 5866.677332746697, 5874.295084589855, 5600.383957764642, 5729.512991395736, 5769.16347129666, 5802.970985145068, 5844.912326315109, 5762.596618236725, 5763.654180749148, 5765.164051212571, 5765.394633563478, 5768.451997060293, 5525.226468294442, 5702.65784052956, 5728.198052272685, 5736.6558006519745, 5766.481016708495, 5134.332536770234, 5134.794608215667, 5140.511651393112, 5144.382674079097, 5144.588432925357, 21956.89021554898, 22375.77624223753, 22646.77353226463, 22715.886420567957, 22899.0, 2234.9553008939893, 2276.3512398966213, 2298.908043678251, 2356.5286942611547, 2414.903403863861, 13557.661058473519, 14181.361838717283, 14450.710985780226, 14485.42058317666, 14696.926515367462, 1903.5146037760283, 1936.06101040995, 1986.2452268138015, 2026.3313106674761, 2036.4297996560986, 49907.0, 68201.65899170522, 73467.63266183688, 74150.62924685396, 76053.61973190153, 9983.450910200008, 10053.396796192106, 10535.209859260585, 10801.621943231949, 10985.780284394268, 13075.542356017493, 13297.534586289434, 13596.38816253271, 14226.928865355814, 14265.0, 8024.398170137263, 8186.631601577943, 8208.261256486945, 8430.831383372299, 8439.746807595782, 8004.719834805754, 8027.678892844221, 8078.272955433921, 8250.711225107092, 8295.751127466187, 6479.740810367627, 6496.805095847132, 6515.641639709766, 6579.144711187515, 6650.434713049348, 1827.972580411295, 1864.2542982806985, 1932.1112288347238, 1934.7775005874223, 1942.1260432805293, 917.2799352508249, 931.5714771205321, 947.3842002698163, 969.069693094637, 1013.4024214040275, 18214.63570728578, 18829.081492368703, 19702.60594788125, 19732.40802775919, 19775.802241977683, 957.5164541906292, 961.7739831139677, 962.5331714118647, 1023.4831410137981, 1046.5499835070993, 10008.299419040743, 11201.71180314258, 11258.43707814606, 11270.774584508265, 11291.774164516828, 365105.0, 385813.0, 385860.0, 389562.0, 389645.0, 59957.70021149866, 78629.60685196538, 80096.59951700205, 82295.0, 84927.15072849316, 967.883853937532, 989.7030890732751, 994.7015895231401, 1012.6405126180247, 1027.1833892055834, 16314.0, 16703.415380461625, 17201.827981720522, 17347.913260433616, 17648.55878602994, 35517.289654207285, 35824.46263306, 36996.63003370039, 37002.814985924895, 38676.41985370166, 47937.0, 48698.26952595643, 49400.30697083915, 49488.0, 50561.24158137557, 7525.58609276495, 7694.769156925301, 7801.336886364723, 7831.099423566135, 8039.035315762146, 8593.226609605166, 11419.31484110956, 12337.198082124632, 12494.937525312314, 12950.935245323713, 10914.890851091388, 10942.23404361687, 11263.324200547993, 11368.94315528417, 11669.474873630877, 10834.512446939749, 11014.944925275322, 11086.0, 11198.328100314007, 11203.663890083311, 11207.551697931991, 11351.091912647134, 11507.597234096766, 11588.594399195988, 11982.281063136239, 7668.654910528928, 7819.569923654253, 7868.8819667706175, 7972.0, 8032.07631122405, 9572.721029530221, 11024.55842306456, 11158.274712143684, 11250.66248012561, 11380.658580242605], "value": 8944.123042343786, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.3191540398998, 1370.636781252968, 1389.6595334143133, 1462.378489142115, 1664.093069277243, 6894.379505844479, 7625.275598818111, 7882.251186137308, 7889.329407000405, 8044.195580441953, 7852.8036799080055, 7929.563873986928, 7934.2065793420625, 7937.0, 7939.5633240171765, 1849.6948003579394, 1912.177763561675, 2070.7204527388762, 2107.673310636854, 2107.8524503284775, 2088.634488964433, 2150.2366659835766, 2153.289414493221, 2153.5800518898823, 2160.773118822525, 2113.2392338758036, 2128.382768996989, 2128.4040468668795, 2131.126238242321, 2131.893405329727, 2088.770235274126, 2108.493961449241, 2110.767815540297, 2113.0174468872046, 2113.5455876986366, 2054.8869812160324, 2065.865718728278, 2076.6054449654666, 2099.7060411542398, 2104.0111147760526, 8063.919360806375, 8072.112067672551, 8162.959185204096, 8177.509349438993, 8177.672893084269, 4706.317583950308, 4826.638002149853, 4826.903461930741, 4839.249916262985, 4840.15297322967, 2937.5593660950813, 3080.7073328034026, 3122.7657925655667, 3159.0680749179046, 3195.6325022622464, 951.8143961927427, 991.2070343724992, 1007.8790545134558, 1023.9590416383336, 1070.978580428387, 11228.606998755085, 11295.039921606673, 11306.943465282704, 11307.321560706385, 11318.320900745899, 4001.979990100031, 4306.246406878847, 4322.8703138905885, 4351.456067991501, 4387.868363949086, 9284.350095493252, 9287.257019438428, 9297.163255307054, 9300.023497532797, 9305.25557955362, 4887.095887260797, 4996.075725990703, 5078.644494885397, 5101.183810590288, 5105.846824595268, 4424.823007079745, 4498.595126438636, 4647.581717645428, 4663.976680116578, 4664.323673067454, 3536.2397084626928, 3596.1908570571413, 3613.2050948791225, 3640.9453858192146, 3692.7230457715777, 1108.9722756931142, 1125.5554056147776, 1131.7453572946185, 1135.7047167736448, 1137.7553825927466, 832.76682528892, 923.6259314977444, 939.8919124300656, 951.9524023798921, 963.9228861691048, 5248.580113590903, 5324.201369794562, 5483.177523371526, 5618.550515958714, 5702.315722113373, 420.67818119138883, 423.5552669696868, 424.54149518519927, 433.4019053705866, 435.414367675476, 3633.945490817587, 3661.1945372018113, 3664.358737221029, 3694.852205911787, 3832.80835958201, 9729.270304727173, 10177.389356638625, 10182.319917213577, 10188.0, 10191.7452063699, 9972.052654997764, 10047.447390393452, 10092.243081768896, 10157.39055656663, 10184.643537476153, 7574.924250757532, 7674.270944260286, 7806.180351063056, 7814.57019863913, 7815.101263354673, 5266.315379000705, 5510.118381059011, 5545.805896793593, 5584.720763961867, 5684.687342196219, 9203.585838637144, 9293.070692930653, 9306.72079837606, 9319.58061887203, 9347.906520934703, 9316.394434361744, 9395.2953528487, 9401.858972115288, 9455.243580513405, 9511.334206605472, 5905.8014235158935, 6387.968060159669, 6739.932600674127, 6790.490713196629, 6933.583984960918, 7724.652390642547, 7733.149353571215, 7793.0, 7795.883061754192, 7804.297613214838, 6796.898046529207, 6893.517453778188, 6912.654367281716, 7109.324614161646, 7128.928710712827, 6689.331066893272, 6771.322867713189, 6797.89803152963, 6994.965025174842, 7012.544184627983, 6507.154069970874, 6522.15212022434, 6567.770128045496, 6575.769848055295, 6583.703733332108, 4754.50077741846, 4760.143174228534, 4773.618110551078, 4775.403074615673, 4864.805407783649, 4757.9048419032115, 4810.0, 4931.654784165074, 4951.183054795918, 4963.255511673278], "value": 4166.602931284289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.4482469409457, 364.0534610013964, 365.3259735787472, 373.3764613096131, 374.5542804063163, 5198.376194856618, 5244.68531888087, 5251.973740131299, 5258.211268309756, 5273.736313184345, 4998.500149985, 4999.450060493343, 5141.331626888508, 5182.041322355358, 5188.92216616751, 4128.3601041838565, 4154.688398370135, 4161.459010328653, 4162.750234985895, 4177.415161877339, 4123.793810309473, 4142.606452387018, 4154.875353739378, 4169.749815011109, 4170.582941705836, 3941.4481972523868, 4008.1983603279314, 4116.115035267417, 4132.628063474287, 4135.400366946791, 4094.6110119538744, 4101.1387608601935, 4108.753474791507, 4119.155573107506, 4131.400946862718, 3634.254977729559, 3667.504886840269, 3680.9263814723554, 3692.5015122958325, 3718.9442158367647, 4762.476127625959, 4982.476839931791, 5013.47358527353, 5031.195008798574, 5095.210242412395, 4551.771021824122, 4562.520935301779, 4581.518940511231, 4592.563706447882, 4596.770161491912, 5441.428649991773, 5451.936872309901, 5459.890802183934, 5472.370677372073, 5477.1510415885605, 3805.1058001369656, 3882.9805850970847, 3886.2227554489214, 3896.5324161100566, 3922.2743792398214, 5526.640768350045, 5596.888062238732, 5610.410906854762, 5621.184928185431, 5622.775088996435, 3980.2636512245326, 3983.0440694233753, 3984.621460961204, 3985.6612187964347, 4003.199360127986, 3957.8416863325147, 4020.7788571628257, 4026.516817981861, 4038.7980600970423, 4049.7772622506045, 4502.032063106448, 4535.478419981744, 4593.724376537419, 4601.7699115044115, 4622.537746225418, 5340.385855626652, 5373.0, 5385.522659576561, 5390.029794636925, 5445.183222516575, 1337.6388375138658, 1342.6441992871896, 1348.9460421583224, 1352.6956434802091, 1352.959411217665, 895.8387490251687, 923.4136323434667, 928.3640706116332, 930.2557953637198, 940.4028441939417, 5118.513741194581, 5149.0, 5159.17453207485, 5162.5095615916425, 5168.689878607295, 5441.319835020622, 5450.88256907341, 5453.454654534515, 5457.781688732485, 5466.0, 5039.420466646309, 5058.8482345528955, 5059.544640982329, 5125.179971204664, 5207.739613019334, 2822.379076603144, 2823.4776566335295, 2835.3903910658896, 2847.917791239357, 2856.928576785597, 5407.405185429599, 5491.066518691832, 5517.69652669107, 5596.664200148004, 5598.524125449382, 5357.142857142839, 5362.544183744348, 5417.458254174551, 5464.672119672832, 5478.890422191614, 5550.834324791758, 5572.944270557324, 5574.888502229933, 5644.8871022579315, 5647.830565083054, 5289.57683385336, 5309.150535914235, 5319.25530425733, 5499.697516636624, 5523.972380138073, 5230.561595561251, 5294.364676238876, 5335.306410166809, 5345.518903298722, 5369.6509726867625, 5560.972195138998, 5564.860878478071, 5593.356763972195, 5610.887782244414, 5645.774169033357, 3707.888763337104, 3815.332316844597, 3817.9809100954317, 3838.4626152338137, 3910.966297525214, 5284.550813180769, 5294.814681486129, 5298.735063246745, 5338.759755811076, 5437.728113594384, 5392.487713667196, 5473.01485732556, 5486.341639003345, 5500.752466138953, 5520.585956053232, 5441.401445841034, 5490.615656903979, 5504.8253343783645, 5513.476219759116, 5522.889542209214, 5215.713135777569, 5364.38309594386, 5409.648372855751, 5424.430434804289, 5481.232627432083, 5397.190421436816, 5430.67415955044, 5448.7548060336585, 5503.504684578407, 5505.0, 5284.26020357158, 5294.788208471772, 5318.308619879391, 5425.701586412785, 5433.945660543344], "value": 4126.9792249855345, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.6819665248963, 720.3480849830903, 738.4387865222433, 748.9251074892511, 788.2905385153365, 14619.269036548183, 15775.84224157758, 15792.0, 15803.92098039511, 15804.841951580494, 13430.798538021938, 14748.26258687064, 14808.0, 14915.850841491581, 14925.328360223788, 777.2344240922687, 803.0684406088909, 809.2352726673296, 809.4414853750923, 809.5830647216688, 803.5098589860181, 805.7018903005896, 806.6410447350931, 806.9072056713466, 807.0839597057324, 844.0968164064453, 844.3287586368821, 846.289117141601, 848.7326492154982, 849.4223927729141, 840.0339609449121, 842.1368097699846, 842.3976856547567, 842.7766641839949, 845.7420486751538, 609.6037575575867, 611.6758118197363, 617.5491890919653, 618.4495798739081, 618.4742968476804, 13779.724405511834, 13804.17174969505, 14979.625509362244, 15873.92063039689, 16125.193740313054, 14785.778213326701, 14963.476278330316, 15386.384544618202, 15430.845691543052, 15575.532734017997, 11821.408929553489, 11977.023791074462, 12244.571439999647, 12459.937700311531, 12476.18904771196, 3586.1393265616066, 3625.6011838697723, 3679.172186258097, 3687.7602955808056, 3727.310447567209, 44429.55570444319, 50639.0, 51192.0, 57211.71394143044, 75418.24581754167, 11584.536618535165, 12000.159988800875, 12164.939175304067, 12507.874921250672, 12889.742205155844, 22145.557088858455, 22363.88818055932, 22401.0, 22554.436139096324, 22588.096476141098, 8205.261526462527, 8424.410291279673, 8439.95780021096, 8454.915450845412, 8555.700550480704, 4401.449818772653, 4435.534268901719, 4485.551444855489, 4493.662975276868, 4531.45622525299, 3395.151212196951, 3508.9298214035575, 3669.3578623740736, 3799.7340186186702, 3804.4863943367845, 620.7485968182893, 623.7598524568074, 624.1355722324613, 627.4729227448964, 631.8009826904511, 633.6134957675814, 671.214678825775, 673.8955461903413, 682.1950098883365, 706.1385110165589, 9143.542822858963, 9149.268058555299, 9473.431594104375, 9696.17582505495, 9789.804203915883, 652.3346186889441, 692.5117791956659, 699.488300462022, 717.2504732554389, 725.5429079679874, 4567.840125595655, 4663.27719203524, 4678.953210467852, 4685.81256749733, 4706.458757242893, 77550.0, 80691.0, 80792.0, 81384.0, 81893.0, 57038.42961570414, 58644.70677646584, 70001.2999870005, 70105.0, 70919.0, 55227.72386138044, 58745.412545873995, 59335.0, 60561.0, 60780.392196078355, 9248.121428464285, 9311.314474220217, 9439.386439881386, 9552.474613896302, 9644.855327170239, 41133.58866411298, 41585.37621935734, 42263.57736422597, 42852.286248513265, 42852.57147428486, 52527.0, 52947.73526132345, 55530.0, 56514.15228771654, 58238.4176158233, 12621.684457888627, 12862.163959342613, 12918.935405322913, 13024.046393041119, 13156.342182890625, 13888.097273677178, 14387.352569134624, 14614.85385146135, 14882.032667876554, 15550.611234719223, 12168.939155304166, 12608.621741347773, 12754.0, 13536.661583460098, 13957.302134893418, 11196.272242304223, 11242.269252498889, 11462.254953428, 11645.48608680866, 11710.882891170979, 12507.499700011898, 12703.809442858179, 12991.220526768424, 13096.869031309565, 13967.161970281815, 9792.363496372713, 10312.071913527818, 10462.63380781669, 10562.207834412297, 11132.721681958015, 10561.41912194806, 11936.641900742992, 12065.457054432747, 12083.879161208628, 12087.39563021863], "value": 7083.270002735253, "unit": "score"}], "name": "dromaeo_css", "value": 6877.717020707415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 113.0, 113.0, 111.0, 113.0, 111.0, 113.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 124.0, 124.0, 174.0, 125.0, 125.0, 127.0, 123.0, 149.0, 125.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 159.0, 162.0, 164.0, 163.0, 163.0, 164.0, 163.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 77.0, 113.0, 78.0, 80.0, 76.0, 76.0, 102.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 104.0, 84.0, 84.0, 84.0, 99.0, 84.0, 85.0, 102.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0, 127.0], "value": 126.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 145.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 55.0, 53.0, 53.0, 53.0, 54.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 64.0, 65.0, 66.0, 64.0, 65.0, 64.0, 65.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 85.0, 86.0, 85.0, 93.0, 94.0, 132.0, 83.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 134.0, 131.0, 140.0, 131.0, 130.0, 130.0, 134.0, 149.0, 132.0], "value": 135.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 171.0, 181.0, 170.0, 172.0, 169.0, 173.0, 169.0, 174.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 67.0, 65.0, 67.0, 67.0, 67.0, 65.0, 64.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1531.1000000000001, "alertThreshold": 2.0}]} 05:08:33 INFO - Return code: 0 05:08:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:08:33 INFO - # TBPL SUCCESS # 05:08:33 INFO - Running post-action listener: _resource_record_post_action 05:08:33 INFO - [mozharness: 2016-12-10 13:08:33.506794Z] Finished run-tests step (success) 05:08:33 INFO - Running post-run listener: _resource_record_post_run 05:08:33 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398757888; Read time: 12; Write time: 255740 05:08:33 INFO - TinderboxPrint: CPU usage
13.2% 05:08:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:08:33 INFO - TinderboxPrint: I/O write bytes / time
398,757,888 / 255,740 05:08:33 INFO - TinderboxPrint: CPU idle
8,294.8 (86.8%) 05:08:33 INFO - TinderboxPrint: CPU user
1,211.3 (12.7%) 05:08:33 INFO - TinderboxPrint: Swap in / out
0 / 0 05:08:33 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103964672; Read time: 0; Write time: 77228 05:08:33 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 294793216; Read time: 0; Write time: 178512 05:08:34 INFO - Running post-run listener: _upload_blobber_files 05:08:34 INFO - Blob upload gear active. 05:08:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:34 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 05:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:34 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:08:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:08:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:35 INFO - (blobuploader) - INFO - Done attempting. 05:08:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:08:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:38 INFO - (blobuploader) - INFO - Done attempting. 05:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:39 INFO - (blobuploader) - INFO - Done attempting. 05:08:39 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:39 INFO - Return code: 0 05:08:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c33f0e90e1090f151d8bca526f96d9c3bfc5ef2da00f9e9399fb019612dc554c42b43b48231bd4af420755fbdc7f1a8fb99531af2fc061dc69c1f37c63ff2f84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee5da3775f56cf76ade13085b5c7cb3f36aa2899f9a98c684e732ccc5502c7148396723485144c8d543aca045507854f7d7fad5bc07edbbacbbb3c24e0a3e9ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c128eace6fddb88956f6ebdf74c45371713be3934d064353f9c8e525604e40e3730440a19c099704b17ead6194bb713818cd9829a79cd58308130df4f344a81"} 05:08:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:39 INFO - Contents: 05:08:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c33f0e90e1090f151d8bca526f96d9c3bfc5ef2da00f9e9399fb019612dc554c42b43b48231bd4af420755fbdc7f1a8fb99531af2fc061dc69c1f37c63ff2f84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee5da3775f56cf76ade13085b5c7cb3f36aa2899f9a98c684e732ccc5502c7148396723485144c8d543aca045507854f7d7fad5bc07edbbacbbb3c24e0a3e9ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c128eace6fddb88956f6ebdf74c45371713be3934d064353f9c8e525604e40e3730440a19c099704b17ead6194bb713818cd9829a79cd58308130df4f344a81"} 05:08:39 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:39 INFO - Copying logs to upload dir... 05:08:39 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.988265 ========= master_lag: 0.56 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-10 05:08:39.454730) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 05:08:39.458102) ========= 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=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c33f0e90e1090f151d8bca526f96d9c3bfc5ef2da00f9e9399fb019612dc554c42b43b48231bd4af420755fbdc7f1a8fb99531af2fc061dc69c1f37c63ff2f84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee5da3775f56cf76ade13085b5c7cb3f36aa2899f9a98c684e732ccc5502c7148396723485144c8d543aca045507854f7d7fad5bc07edbbacbbb3c24e0a3e9ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c128eace6fddb88956f6ebdf74c45371713be3934d064353f9c8e525604e40e3730440a19c099704b17ead6194bb713818cd9829a79cd58308130df4f344a81"} build_url:https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011639 build_url: 'https://queue.taskcluster.net/v1/task/DRMubqrNQVCW3PR22s25aA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c33f0e90e1090f151d8bca526f96d9c3bfc5ef2da00f9e9399fb019612dc554c42b43b48231bd4af420755fbdc7f1a8fb99531af2fc061dc69c1f37c63ff2f84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee5da3775f56cf76ade13085b5c7cb3f36aa2899f9a98c684e732ccc5502c7148396723485144c8d543aca045507854f7d7fad5bc07edbbacbbb3c24e0a3e9ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c128eace6fddb88956f6ebdf74c45371713be3934d064353f9c8e525604e40e3730440a19c099704b17ead6194bb713818cd9829a79cd58308130df4f344a81"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 05:08:39.483925) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 05:08:39.484268) ========= 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=cbe409c6de289db6ac707de3000001d4-1481366767.789577-396147274 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004644 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 05:08:39.534632) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 05:08:39.534957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 05:08:39.535267) ========= ========= Total master_lag: 0.70 =========