builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1483113877.8 results: success (0) buildid: 20161230070524 builduid: d270c7529bbe4bc381cd4b027230fa93 revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:37.800520) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:37.800906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:37.801175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:37.812909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:37.813196) ========= 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=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-30 08:04:38-- 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% 48.4K=0.2s 2016-12-30 08:04:38 (48.4 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.413354 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:38.238015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:38.238343) ========= 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=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017015 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:38.289483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:38.289840) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --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 9104708cc3ac0ccfe4cf5d518e13736773c565d7 --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=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-30 08:04:38,766 truncating revision to first 12 chars 2016-12-30 08:04:38,766 Setting DEBUG logging. 2016-12-30 08:04:38,766 attempt 1/10 2016-12-30 08:04:38,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9104708cc3ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 08:04:39,324 unpacking tar archive at: mozilla-central-9104708cc3ac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.868044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:39.173493) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:39.174031) ========= script_repo_revision: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:39.174689) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:39.175152) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 08:04:39.187447) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-30 08:04:39.188053) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:04:39 INFO - MultiFileLogger online at 20161230 08:04:39 in /builds/slave/test 08:04:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:04:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:04:39 INFO - {'append_to_log': False, 08:04:39 INFO - 'base_work_dir': '/builds/slave/test', 08:04:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:04:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:04:39 INFO - 'branch': 'Firefox-Non-PGO', 08:04:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:04:39 INFO - 'config_files': ('talos/linux_config.py',), 08:04:39 INFO - 'default_actions': ('clobber', 08:04:39 INFO - 'read-buildbot-config', 08:04:39 INFO - 'download-and-extract', 08:04:39 INFO - 'populate-webroot', 08:04:39 INFO - 'create-virtualenv', 08:04:39 INFO - 'install', 08:04:39 INFO - 'run-tests'), 08:04:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:04:39 INFO - 'download_minidump_stackwalk': True, 08:04:39 INFO - 'download_symbols': 'ondemand', 08:04:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:04:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:04:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:04:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:04:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:04:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:04:39 INFO - 'installer_path': 'installer.exe', 08:04:39 INFO - 'log_level': 'info', 08:04:39 INFO - 'log_name': 'talos', 08:04:39 INFO - 'log_to_console': True, 08:04:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:04:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:04:39 INFO - 'opt_config_files': (), 08:04:39 INFO - 'pip_index': False, 08:04:39 INFO - 'sps_profile': False, 08:04:39 INFO - 'sps_profile_interval': 0, 08:04:39 INFO - 'suite': 'dromaeojs', 08:04:39 INFO - 'system_bits': '32', 08:04:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:04:39 INFO - 'title': 'talos-linux64-ix-014', 08:04:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:04:39 INFO - 'use_talos_json': True, 08:04:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:04:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:04:39 INFO - 'work_dir': 'build'} 08:04:39 INFO - [mozharness: 2016-12-30 16:04:39.757686Z] Running clobber step. 08:04:39 INFO - Running pre-action listener: _resource_record_pre_action 08:04:39 INFO - Running main action method: clobber 08:04:39 INFO - rmtree: /builds/slave/test/build 08:04:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:04:40 INFO - Running post-action listener: _resource_record_post_action 08:04:40 INFO - [mozharness: 2016-12-30 16:04:40.219652Z] Finished clobber step (success) 08:04:40 INFO - [mozharness: 2016-12-30 16:04:40.219754Z] Running read-buildbot-config step. 08:04:40 INFO - Running pre-action listener: _resource_record_pre_action 08:04:40 INFO - Running main action method: read_buildbot_config 08:04:40 INFO - Using buildbot properties: 08:04:40 INFO - { 08:04:40 INFO - "project": "", 08:04:40 INFO - "product": "firefox", 08:04:40 INFO - "script_repo_revision": "production", 08:04:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:04:40 INFO - "repository": "", 08:04:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:04:40 INFO - "buildid": "20161230070524", 08:04:40 INFO - "pgo_build": "False", 08:04:40 INFO - "basedir": "/builds/slave/test", 08:04:40 INFO - "buildnumber": 891, 08:04:40 INFO - "slavename": "talos-linux64-ix-014", 08:04:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:04:40 INFO - "platform": "ubuntu64_hw", 08:04:40 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:04:40 INFO - "branch": "mozilla-central", 08:04:40 INFO - "repo_path": "mozilla-central", 08:04:40 INFO - "revision": "9104708cc3ac0ccfe4cf5d518e13736773c565d7", 08:04:40 INFO - "stage_platform": "linux64", 08:04:40 INFO - "builduid": "d270c7529bbe4bc381cd4b027230fa93", 08:04:40 INFO - "slavebuilddir": "test" 08:04:40 INFO - } 08:04:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:04:40 INFO - Running post-action listener: _resource_record_post_action 08:04:40 INFO - [mozharness: 2016-12-30 16:04:40.222201Z] Finished read-buildbot-config step (success) 08:04:40 INFO - [mozharness: 2016-12-30 16:04:40.222290Z] Running download-and-extract step. 08:04:40 INFO - Running pre-action listener: _resource_record_pre_action 08:04:40 INFO - Running main action method: download_and_extract 08:04:40 INFO - mkdir: /builds/slave/test/build/tests 08:04:40 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')]} 08:04:40 INFO - trying https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:04:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/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 08:04:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/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 08:04:40 INFO - Downloaded 1361 bytes. 08:04:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:04:40 INFO - Using the following test package requirements: 08:04:40 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:04:40 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:04:40 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:04:40 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'jsshell-linux-x86_64.zip'], 08:04:40 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:04:40 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:04:40 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:04:40 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:04:40 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:04:40 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:04:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:04:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:04:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:04:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:04:40 INFO - Fetch https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:04:42 INFO - Expected file size: 20246276 08:04:42 INFO - Obtained file size: 20246276 08:04:46 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 08:04:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:04:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:04:46 INFO - Fetch https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:04:46 INFO - Expected file size: 20246276 08:04:46 INFO - Obtained file size: 20246276 08:04:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:04:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:04:51 INFO - Fetch https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:04:53 INFO - Expected file size: 14409776 08:04:53 INFO - Obtained file size: 14409776 08:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:53 INFO - trying https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:04:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/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 08:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/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 08:04:55 INFO - Downloaded 55909639 bytes. 08:04:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:04:55 INFO - mkdir: /builds/slave/test/properties 08:04:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:04:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:04:55 INFO - Contents: 08:04:55 INFO - build_url:https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:04:56 INFO - Running post-action listener: _resource_record_post_action 08:04:56 INFO - Running post-action listener: set_extra_try_arguments 08:04:56 INFO - [mozharness: 2016-12-30 16:04:56.186868Z] Finished download-and-extract step (success) 08:04:56 INFO - [mozharness: 2016-12-30 16:04:56.187094Z] Running populate-webroot step. 08:04:56 INFO - Running pre-action listener: _resource_record_pre_action 08:04:56 INFO - Running main action method: populate_webroot 08:04:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:04:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:04:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:04:56 INFO - '--tppagecycles', 08:04:56 INFO - '7'], 08:04:56 INFO - 'tests': ['tsvgm']}}, 08:04:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:04:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:04:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:04:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:04:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['damp', 'tps']}, 08:04:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'tests': ['damp', 'tps']}, 08:04:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['dromaeo_dom']}, 08:04:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:04:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['basic_compositor_video']}, 08:04:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:04:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'other-e10s': {'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['a11yr', 08:04:56 INFO - 'ts_paint', 08:04:56 INFO - 'tpaint', 08:04:56 INFO - 'sessionrestore', 08:04:56 INFO - 'sessionrestore_no_auto_restore', 08:04:56 INFO - 'tabpaint']}, 08:04:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['tsvgx', 08:04:56 INFO - 'tsvgr_opacity', 08:04:56 INFO - 'tart', 08:04:56 INFO - 'tscrollx', 08:04:56 INFO - 'cart', 08:04:56 INFO - 'tsvg_static']}, 08:04:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:04:56 INFO - 'tsvgr_opacity', 08:04:56 INFO - 'tart', 08:04:56 INFO - 'tscrollx', 08:04:56 INFO - 'cart', 08:04:56 INFO - 'tsvg_static']}, 08:04:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'talos_options': ['--disable-e10s'], 08:04:56 INFO - 'tests': ['tp5o']}, 08:04:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:04:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'talos_options': ['--disable-e10s', 08:04:56 INFO - '--xperf_path', 08:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:56 INFO - 'tests': ['tp5n']}, 08:04:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:04:56 INFO - 'talos_options': ['--xperf_path', 08:04:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:56 INFO - 'tests': ['tp5n']}}, 08:04:56 INFO - 'talos.zip': {'path': '', 08:04:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:04:56 INFO - Running post-action listener: _resource_record_post_action 08:04:56 INFO - [mozharness: 2016-12-30 16:04:56.204108Z] Finished populate-webroot step (success) 08:04:56 INFO - [mozharness: 2016-12-30 16:04:56.204196Z] Running create-virtualenv step. 08:04:56 INFO - Running pre-action listener: _resource_record_pre_action 08:04:56 INFO - Running main action method: create_virtualenv 08:04:56 INFO - Creating virtualenv /builds/slave/test/build/venv 08:04:56 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 08:04:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:04:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:04:56 INFO - Using real prefix '/usr' 08:04:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:04:56 INFO - Installing distribute.............................................................................................................................................................................................done. 08:04:58 INFO - Installing pip.................done. 08:04:58 INFO - Return code: 0 08:04:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:04:59 INFO - Reading from file tmpfile_stdout 08:04:59 INFO - Output received: 08:04:59 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) 08:04:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:04:59 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')]} 08:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:59 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')]} 08:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:59 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:04:59 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 08:04:59 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 08:04:59 INFO - Using env: {'DISPLAY': ':0', 08:04:59 INFO - 'HOME': '/home/cltbld', 08:04:59 INFO - 'LANG': 'en_US.UTF-8', 08:04:59 INFO - 'LANGUAGE': 'en_US:en', 08:04:59 INFO - 'LOGNAME': 'cltbld', 08:04:59 INFO - 'MAIL': '/var/mail/cltbld', 08:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:59 INFO - 'MOZ_NO_REMOTE': '1', 08:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:04:59 INFO - 'NO_EM_RESTART': '1', 08:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:59 INFO - 'PWD': '/builds/slave/test', 08:04:59 INFO - 'SHELL': '/bin/bash', 08:04:59 INFO - 'SHLVL': '1', 08:04:59 INFO - 'TERM': 'linux', 08:04:59 INFO - 'TMOUT': '86400', 08:04:59 INFO - 'USER': 'cltbld', 08:04:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:59 INFO - '_': '/tools/buildbot/bin/python'} 08:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:59 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 08:04:59 INFO - Cleaning up... 08:04:59 INFO - Return code: 0 08:04:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:04:59 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')]} 08:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:59 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')]} 08:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:59 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:04:59 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 08:04:59 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 08:04:59 INFO - Using env: {'DISPLAY': ':0', 08:04:59 INFO - 'HOME': '/home/cltbld', 08:04:59 INFO - 'LANG': 'en_US.UTF-8', 08:04:59 INFO - 'LANGUAGE': 'en_US:en', 08:04:59 INFO - 'LOGNAME': 'cltbld', 08:04:59 INFO - 'MAIL': '/var/mail/cltbld', 08:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:59 INFO - 'MOZ_NO_REMOTE': '1', 08:04:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:04:59 INFO - 'NO_EM_RESTART': '1', 08:04:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:04:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:59 INFO - 'PWD': '/builds/slave/test', 08:04:59 INFO - 'SHELL': '/bin/bash', 08:04:59 INFO - 'SHLVL': '1', 08:04:59 INFO - 'TERM': 'linux', 08:04:59 INFO - 'TMOUT': '86400', 08:04:59 INFO - 'USER': 'cltbld', 08:04:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:59 INFO - '_': '/tools/buildbot/bin/python'} 08:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:59 INFO - Downloading/unpacking psutil>=3.1.1 08:04:59 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 08:04:59 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 08:04:59 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 08:04:59 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 08:04:59 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 08:04:59 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 08:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:05:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:01 INFO - Installing collected packages: psutil 08:05:01 INFO - Running setup.py install for psutil 08:05:01 INFO - building 'psutil._psutil_linux' extension 08:05:01 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 08:05:01 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 08:05:01 INFO - building 'psutil._psutil_posix' extension 08:05:01 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 08:05:01 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 08:05:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:02 INFO - Successfully installed psutil 08:05:02 INFO - Cleaning up... 08:05:02 INFO - Return code: 0 08:05:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:02 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:02 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 08:05:02 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 08:05:02 INFO - Using env: {'DISPLAY': ':0', 08:05:02 INFO - 'HOME': '/home/cltbld', 08:05:02 INFO - 'LANG': 'en_US.UTF-8', 08:05:02 INFO - 'LANGUAGE': 'en_US:en', 08:05:02 INFO - 'LOGNAME': 'cltbld', 08:05:02 INFO - 'MAIL': '/var/mail/cltbld', 08:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:02 INFO - 'MOZ_NO_REMOTE': '1', 08:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:02 INFO - 'NO_EM_RESTART': '1', 08:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:02 INFO - 'PWD': '/builds/slave/test', 08:05:02 INFO - 'SHELL': '/bin/bash', 08:05:02 INFO - 'SHLVL': '1', 08:05:02 INFO - 'TERM': 'linux', 08:05:02 INFO - 'TMOUT': '86400', 08:05:02 INFO - 'USER': 'cltbld', 08:05:02 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:02 INFO - '_': '/tools/buildbot/bin/python'} 08:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:05:02 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 08:05:02 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 08:05:02 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 08:05:02 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 08:05:02 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 08:05:02 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 08:05:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:05:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:05:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:05:04 INFO - Installing collected packages: mozsystemmonitor 08:05:04 INFO - Running setup.py install for mozsystemmonitor 08:05:04 INFO - Successfully installed mozsystemmonitor 08:05:04 INFO - Cleaning up... 08:05:04 INFO - Return code: 0 08:05:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:04 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:04 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 08:05:04 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 08:05:04 INFO - Using env: {'DISPLAY': ':0', 08:05:04 INFO - 'HOME': '/home/cltbld', 08:05:04 INFO - 'LANG': 'en_US.UTF-8', 08:05:04 INFO - 'LANGUAGE': 'en_US:en', 08:05:04 INFO - 'LOGNAME': 'cltbld', 08:05:04 INFO - 'MAIL': '/var/mail/cltbld', 08:05:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:04 INFO - 'MOZ_NO_REMOTE': '1', 08:05:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:04 INFO - 'NO_EM_RESTART': '1', 08:05:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:04 INFO - 'PWD': '/builds/slave/test', 08:05:04 INFO - 'SHELL': '/bin/bash', 08:05:04 INFO - 'SHLVL': '1', 08:05:04 INFO - 'TERM': 'linux', 08:05:04 INFO - 'TMOUT': '86400', 08:05:04 INFO - 'USER': 'cltbld', 08:05:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:04 INFO - '_': '/tools/buildbot/bin/python'} 08:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:04 INFO - Downloading/unpacking jsonschema==2.5.1 08:05:04 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 08:05:04 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 08:05:04 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 08:05:04 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 08:05:04 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 08:05:04 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 08:05:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05:06 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 08:05:06 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 08:05:06 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 08:05:06 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 08:05:06 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 08:05:06 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 08:05:06 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:07 INFO - warning: no files found matching '*.txt' 08:05:07 INFO - no previously-included directories found matching 'build' 08:05:07 INFO - no previously-included directories found matching 'dist' 08:05:07 INFO - no previously-included directories found matching '.git*' 08:05:07 INFO - Installing collected packages: jsonschema, functools32 08:05:07 INFO - Running setup.py install for functools32 08:05:07 INFO - warning: no files found matching '*.txt' 08:05:07 INFO - no previously-included directories found matching 'build' 08:05:07 INFO - no previously-included directories found matching 'dist' 08:05:07 INFO - no previously-included directories found matching '.git*' 08:05:07 INFO - Successfully installed jsonschema functools32 08:05:07 INFO - Cleaning up... 08:05:07 INFO - Return code: 0 08:05:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:05:07 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')]} 08:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:07 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')]} 08:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:07 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:07 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 08:05:07 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 08:05:07 INFO - Using env: {'DISPLAY': ':0', 08:05:07 INFO - 'HOME': '/home/cltbld', 08:05:07 INFO - 'LANG': 'en_US.UTF-8', 08:05:07 INFO - 'LANGUAGE': 'en_US:en', 08:05:07 INFO - 'LOGNAME': 'cltbld', 08:05:07 INFO - 'MAIL': '/var/mail/cltbld', 08:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:07 INFO - 'MOZ_NO_REMOTE': '1', 08:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:07 INFO - 'NO_EM_RESTART': '1', 08:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:07 INFO - 'PWD': '/builds/slave/test', 08:05:07 INFO - 'SHELL': '/bin/bash', 08:05:07 INFO - 'SHLVL': '1', 08:05:07 INFO - 'TERM': 'linux', 08:05:07 INFO - 'TMOUT': '86400', 08:05:07 INFO - 'USER': 'cltbld', 08:05:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:07 INFO - '_': '/tools/buildbot/bin/python'} 08:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:05:07 INFO - Cleaning up... 08:05:07 INFO - Return code: 0 08:05:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:07 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')]} 08:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:07 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')]} 08:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:07 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:07 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 08:05:07 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 08:05:07 INFO - Using env: {'DISPLAY': ':0', 08:05:07 INFO - 'HOME': '/home/cltbld', 08:05:07 INFO - 'LANG': 'en_US.UTF-8', 08:05:07 INFO - 'LANGUAGE': 'en_US:en', 08:05:07 INFO - 'LOGNAME': 'cltbld', 08:05:07 INFO - 'MAIL': '/var/mail/cltbld', 08:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:07 INFO - 'MOZ_NO_REMOTE': '1', 08:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:07 INFO - 'NO_EM_RESTART': '1', 08:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:07 INFO - 'PWD': '/builds/slave/test', 08:05:07 INFO - 'SHELL': '/bin/bash', 08:05:07 INFO - 'SHLVL': '1', 08:05:07 INFO - 'TERM': 'linux', 08:05:07 INFO - 'TMOUT': '86400', 08:05:07 INFO - 'USER': 'cltbld', 08:05:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:07 INFO - '_': '/tools/buildbot/bin/python'} 08:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:07 INFO - Downloading/unpacking blobuploader==1.2.4 08:05:07 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 08:05:07 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 08:05:07 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 08:05:07 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 08:05:07 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 08:05:07 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 08:05:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:09 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 08:05:09 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 08:05:09 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 08:05:09 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 08:05:09 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 08:05:09 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 08:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:10 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 08:05:10 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 08:05:10 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 08:05:10 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 08:05:10 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 08:05:10 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 08:05:10 INFO - Downloading docopt-0.6.1.tar.gz 08:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:10 INFO - Running setup.py install for blobuploader 08:05:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:10 INFO - Running setup.py install for requests 08:05:10 INFO - Running setup.py install for docopt 08:05:11 INFO - Successfully installed blobuploader requests docopt 08:05:11 INFO - Cleaning up... 08:05:11 INFO - Return code: 0 08:05:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:11 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')]} 08:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:11 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')]} 08:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:11 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:11 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 08:05:11 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 08:05:11 INFO - Using env: {'DISPLAY': ':0', 08:05:11 INFO - 'HOME': '/home/cltbld', 08:05:11 INFO - 'LANG': 'en_US.UTF-8', 08:05:11 INFO - 'LANGUAGE': 'en_US:en', 08:05:11 INFO - 'LOGNAME': 'cltbld', 08:05:11 INFO - 'MAIL': '/var/mail/cltbld', 08:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:11 INFO - 'MOZ_NO_REMOTE': '1', 08:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:11 INFO - 'NO_EM_RESTART': '1', 08:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:11 INFO - 'PWD': '/builds/slave/test', 08:05:11 INFO - 'SHELL': '/bin/bash', 08:05:11 INFO - 'SHLVL': '1', 08:05:11 INFO - 'TERM': 'linux', 08:05:11 INFO - 'TMOUT': '86400', 08:05:11 INFO - 'USER': 'cltbld', 08:05:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:11 INFO - '_': '/tools/buildbot/bin/python'} 08:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:11 INFO - Running setup.py (path:/tmp/pip-mgbyoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:11 INFO - Running setup.py (path:/tmp/pip-f1am5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:11 INFO - Running setup.py (path:/tmp/pip-6uWuMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:11 INFO - Running setup.py (path:/tmp/pip-tUNj__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:11 INFO - Running setup.py (path:/tmp/pip-aHm721-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:11 INFO - Running setup.py (path:/tmp/pip-j2k9_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:11 INFO - Running setup.py (path:/tmp/pip-G5rkJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:11 INFO - Running setup.py (path:/tmp/pip-WE8tts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:11 INFO - Running setup.py (path:/tmp/pip-MPioor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:11 INFO - Running setup.py (path:/tmp/pip-NGX63z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:12 INFO - Running setup.py (path:/tmp/pip-YVHMXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:12 INFO - Running setup.py (path:/tmp/pip-Fyegsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:12 INFO - Running setup.py (path:/tmp/pip-ySsEIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:12 INFO - Running setup.py (path:/tmp/pip-pJUiyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:12 INFO - Running setup.py (path:/tmp/pip-cD4SRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:12 INFO - Running setup.py (path:/tmp/pip-FRwUt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:12 INFO - Running setup.py (path:/tmp/pip-_SCsc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:12 INFO - Running setup.py install for manifestparser 08:05:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Running setup.py install for mozcrash 08:05:12 INFO - Running setup.py install for mozdebug 08:05:12 INFO - Running setup.py install for mozdevice 08:05:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Running setup.py install for mozfile 08:05:13 INFO - Running setup.py install for mozhttpd 08:05:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for mozinfo 08:05:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for mozInstall 08:05:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for mozleak 08:05:13 INFO - Running setup.py install for mozlog 08:05:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for moznetwork 08:05:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for mozprocess 08:05:13 INFO - Running setup.py install for mozprofile 08:05:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:13 INFO - Running setup.py install for mozrunner 08:05:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:14 INFO - Running setup.py install for mozscreenshot 08:05:14 INFO - Running setup.py install for moztest 08:05:14 INFO - Running setup.py install for mozversion 08:05:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:14 INFO - Cleaning up... 08:05:14 INFO - Return code: 0 08:05:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:14 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:14 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 08:05:14 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 08:05:14 INFO - Using env: {'DISPLAY': ':0', 08:05:14 INFO - 'HOME': '/home/cltbld', 08:05:14 INFO - 'LANG': 'en_US.UTF-8', 08:05:14 INFO - 'LANGUAGE': 'en_US:en', 08:05:14 INFO - 'LOGNAME': 'cltbld', 08:05:14 INFO - 'MAIL': '/var/mail/cltbld', 08:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:14 INFO - 'MOZ_NO_REMOTE': '1', 08:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:14 INFO - 'NO_EM_RESTART': '1', 08:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:14 INFO - 'PWD': '/builds/slave/test', 08:05:14 INFO - 'SHELL': '/bin/bash', 08:05:14 INFO - 'SHLVL': '1', 08:05:14 INFO - 'TERM': 'linux', 08:05:14 INFO - 'TMOUT': '86400', 08:05:14 INFO - 'USER': 'cltbld', 08:05:14 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:14 INFO - '_': '/tools/buildbot/bin/python'} 08:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:14 INFO - Running setup.py (path:/tmp/pip-Aa_MCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:14 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)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:14 INFO - Running setup.py (path:/tmp/pip-6VgTLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:14 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)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:14 INFO - Running setup.py (path:/tmp/pip-XPGDGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:14 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)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:14 INFO - Running setup.py (path:/tmp/pip-7nAjqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:14 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)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:14 INFO - Running setup.py (path:/tmp/pip-DOJ_M3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:14 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)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:14 INFO - Running setup.py (path:/tmp/pip-I5jEdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:15 INFO - Running setup.py (path:/tmp/pip-ltkiMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:15 INFO - Running setup.py (path:/tmp/pip-QvvTEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:15 INFO - Running setup.py (path:/tmp/pip-ext8Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:15 INFO - Running setup.py (path:/tmp/pip-guyx3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:15 INFO - Running setup.py (path:/tmp/pip-Y7hN2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:15 INFO - Running setup.py (path:/tmp/pip-p7K6bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:15 INFO - Running setup.py (path:/tmp/pip-HP1p7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:15 INFO - Running setup.py (path:/tmp/pip-y9ov_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:15 INFO - Running setup.py (path:/tmp/pip-J1gMBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:15 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:15 INFO - Running setup.py (path:/tmp/pip-aK0Y2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:05:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:15 INFO - Running setup.py (path:/tmp/pip-wYLYXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:15 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)) 08:05:15 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)) 08:05:15 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)) 08:05:15 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)) 08:05:15 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)) 08:05:15 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)) 08:05:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05: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 08:05:17 INFO - Downloading blessings-1.6.tar.gz 08:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:17 INFO - Installing collected packages: blessings 08:05:17 INFO - Running setup.py install for blessings 08:05:18 INFO - Successfully installed blessings 08:05:18 INFO - Cleaning up... 08:05:18 INFO - Return code: 0 08:05:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:18 INFO - Reading from file tmpfile_stdout 08:05:18 INFO - Current package versions: 08:05:18 INFO - argparse == 1.2.1 08:05:18 INFO - blessings == 1.6 08:05:18 INFO - blobuploader == 1.2.4 08:05:18 INFO - docopt == 0.6.1 08:05:18 INFO - functools32 == 3.2.3-2 08:05:18 INFO - jsonschema == 2.5.1 08:05:18 INFO - manifestparser == 1.1 08:05:18 INFO - mozInstall == 1.12 08:05:18 INFO - mozcrash == 1.0 08:05:18 INFO - mozdebug == 0.1 08:05:18 INFO - mozdevice == 0.49 08:05:18 INFO - mozfile == 1.2 08:05:18 INFO - mozhttpd == 0.7 08:05:18 INFO - mozinfo == 0.9 08:05:18 INFO - mozleak == 0.1 08:05:18 INFO - mozlog == 3.4 08:05:18 INFO - moznetwork == 0.27 08:05:18 INFO - mozprocess == 0.23 08:05:18 INFO - mozprofile == 0.28 08:05:18 INFO - mozrunner == 6.13 08:05:18 INFO - mozscreenshot == 0.1 08:05:18 INFO - mozsystemmonitor == 0.3 08:05:18 INFO - moztest == 0.8 08:05:18 INFO - mozversion == 1.4 08:05:18 INFO - psutil == 3.1.1 08:05:18 INFO - requests == 1.2.3 08:05:18 INFO - wsgiref == 0.1.2 08:05:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:18 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 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05:18 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 08:05:18 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 08:05:18 INFO - Using env: {'DISPLAY': ':0', 08:05:18 INFO - 'HOME': '/home/cltbld', 08:05:18 INFO - 'LANG': 'en_US.UTF-8', 08:05:18 INFO - 'LANGUAGE': 'en_US:en', 08:05:18 INFO - 'LOGNAME': 'cltbld', 08:05:18 INFO - 'MAIL': '/var/mail/cltbld', 08:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:18 INFO - 'MOZ_NO_REMOTE': '1', 08:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:18 INFO - 'NO_EM_RESTART': '1', 08:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:18 INFO - 'PWD': '/builds/slave/test', 08:05:18 INFO - 'SHELL': '/bin/bash', 08:05:18 INFO - 'SHLVL': '1', 08:05:18 INFO - 'TERM': 'linux', 08:05:18 INFO - 'TMOUT': '86400', 08:05:18 INFO - 'USER': 'cltbld', 08:05:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:18 INFO - '_': '/tools/buildbot/bin/python'} 08:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 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)) 08:05:18 INFO - Cleaning up... 08:05:18 INFO - Return code: 0 08:05:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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')]} 08:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, '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': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', '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 08:05: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', 'jsonschema'] in /builds/slave/test/build 08:05: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 jsonschema 08:05:18 INFO - Using env: {'DISPLAY': ':0', 08:05:18 INFO - 'HOME': '/home/cltbld', 08:05:18 INFO - 'LANG': 'en_US.UTF-8', 08:05:18 INFO - 'LANGUAGE': 'en_US:en', 08:05:18 INFO - 'LOGNAME': 'cltbld', 08:05:18 INFO - 'MAIL': '/var/mail/cltbld', 08:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:18 INFO - 'MOZ_NO_REMOTE': '1', 08:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:18 INFO - 'NO_EM_RESTART': '1', 08:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:18 INFO - 'PWD': '/builds/slave/test', 08:05:18 INFO - 'SHELL': '/bin/bash', 08:05:18 INFO - 'SHLVL': '1', 08:05:18 INFO - 'TERM': 'linux', 08:05:18 INFO - 'TMOUT': '86400', 08:05:18 INFO - 'USER': 'cltbld', 08:05:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:18 INFO - '_': '/tools/buildbot/bin/python'} 08:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:05:18 INFO - Cleaning up... 08:05:18 INFO - Return code: 0 08:05:18 INFO - Running post-action listener: _resource_record_post_action 08:05:18 INFO - Running post-action listener: _start_resource_monitoring 08:05:18 INFO - Starting resource monitoring. 08:05:18 INFO - [mozharness: 2016-12-30 16:05:18.810524Z] Finished create-virtualenv step (success) 08:05:18 INFO - [mozharness: 2016-12-30 16:05:18.810983Z] Running install step. 08:05:18 INFO - Running pre-action listener: _resource_record_pre_action 08:05:18 INFO - Running main action method: install 08:05:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:19 INFO - Reading from file tmpfile_stdout 08:05:19 INFO - Detecting whether we're running mozinstall >=1.0... 08:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:05:19 INFO - Reading from file tmpfile_stdout 08:05:19 INFO - Output received: 08:05:19 INFO - Usage: mozinstall [options] installer 08:05:19 INFO - Options: 08:05:19 INFO - -h, --help show this help message and exit 08:05:19 INFO - -d DEST, --destination=DEST 08:05:19 INFO - Directory to install application into. [default: 08:05:19 INFO - "/builds/slave/test"] 08:05:19 INFO - --app=APP Application being installed. [default: firefox] 08:05:19 INFO - mkdir: /builds/slave/test/build/application 08:05:19 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'] 08:05:19 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 08:05:35 INFO - Reading from file tmpfile_stdout 08:05:35 INFO - Output received: 08:05:35 INFO - /builds/slave/test/build/application/firefox/firefox 08:05:35 INFO - Running post-action listener: _resource_record_post_action 08:05:35 INFO - [mozharness: 2016-12-30 16:05:35.191807Z] Finished install step (success) 08:05:35 INFO - [mozharness: 2016-12-30 16:05:35.192055Z] Running run-tests step. 08:05:35 INFO - Running pre-action listener: _resource_record_pre_action 08:05:35 INFO - Running main action method: run_tests 08:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:05:35 INFO - Python 2.7.3 08:05:35 INFO - Return code: 0 08:05:35 INFO - grabbing minidump binary from tooltool 08:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:35 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 0x1d26850>, '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 0x1c9b6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9bb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:05:35 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 08:05:35 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 08:05:35 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 08:05:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:05:35 INFO - Return code: 0 08:05:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:05:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:05:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:05:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:05:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 08:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 08:05:35 INFO - Using env: {'DISPLAY': ':0', 08:05:35 INFO - 'HOME': '/home/cltbld', 08:05:35 INFO - 'LANG': 'en_US.UTF-8', 08:05:35 INFO - 'LANGUAGE': 'en_US:en', 08:05:35 INFO - 'LOGNAME': 'cltbld', 08:05:35 INFO - 'MAIL': '/var/mail/cltbld', 08:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:35 INFO - 'MOZ_NO_REMOTE': '1', 08:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:05:35 INFO - 'NO_EM_RESTART': '1', 08:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:35 INFO - 'PWD': '/builds/slave/test', 08:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:05:35 INFO - 'SHELL': '/bin/bash', 08:05:35 INFO - 'SHLVL': '1', 08:05:35 INFO - 'TERM': 'linux', 08:05:35 INFO - 'TMOUT': '86400', 08:05:35 INFO - 'USER': 'cltbld', 08:05:35 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492', 08:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:35 INFO - '_': '/tools/buildbot/bin/python'} 08:05:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 08:05:35 INFO - mozversion application_buildid: 20161230070524 08:05:35 INFO - mozversion application_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 08:05:35 INFO - mozversion application_display_name: Nightly 08:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:05:35 INFO - mozversion application_name: Firefox 08:05:35 INFO - mozversion application_remotingname: firefox 08:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:05:35 INFO - mozversion application_vendor: Mozilla 08:05:35 INFO - mozversion application_version: 53.0a1 08:05:35 INFO - mozversion platform_buildid: 20161230070524 08:05:35 INFO - mozversion platform_changeset: 9104708cc3ac0ccfe4cf5d518e13736773c565d7 08:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:05:35 INFO - mozversion platform_version: 53.0a1 08:05:35 INFO - using testdate: 1483113935 08:05:35 INFO - actual date: 1483113935 08:05:35 INFO - starting webserver on 'localhost:35693' 08:05:35 INFO - SUITE-START | Running 2 tests 08:05:35 INFO - TEST-START | dromaeo_css 08:05:35 INFO - operating with platform_type : linux_ 08:05:35 INFO - Initialising browser for dromaeo_css test... 08:05:35 INFO - TEST-INFO | started process 25457 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd5M096/profile http://localhost:35693/getInfo.html) 08:05:35 INFO - PROCESS | 25457 | 1483113935990 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:05:39 INFO - PROCESS | 25457 | __metrics Screen width/height:1600/1200 08:05:39 INFO - PROCESS | 25457 | colorDepth:24 08:05:39 INFO - PROCESS | 25457 | Browser inner width/height: 1024/697 08:05:39 INFO - PROCESS | 25457 | __metrics 08:05:39 INFO - PROCESS | 25457 | 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" 08:05:39 INFO - PROCESS | 25457 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:40 INFO - PROCESS | 25457 | console.error: PushService: 08:05:40 INFO - PROCESS | 25457 | stateChangeProcessEnqueue: Error transitioning state 08:05:40 INFO - PROCESS | 25457 | UnknownError 08:05:41 INFO - TEST-INFO | 25457: exit 0 08:05:41 INFO - Browser initialized. 08:05:41 INFO - Running cycle 1/1 for dromaeo_css test... 08:05:41 INFO - TEST-INFO | started process 25542 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd5M096/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:53 INFO - PROCESS | 25542 | RSS: Main: 151244800 08:05:53 INFO - PROCESS | 25542 | 08:06:43 INFO - PROCESS | 25542 | 1483114003823 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] 08:08:57 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35693/tests/dromaeo/cssquery-ext.html) 08:08:59 INFO - PROCESS | 25542 | RSS: Main: 295636992 08:08:59 INFO - PROCESS | 25542 | 08:10:42 INFO - PROCESS | 25542 | ************************* 08:10:42 INFO - PROCESS | 25542 | A coding exception was thrown and uncaught in a Task. 08:10:42 INFO - PROCESS | 25542 | 08:10:42 INFO - PROCESS | 25542 | Full message: ReferenceError: fetch is not defined 08:10:42 INFO - PROCESS | 25542 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:10:42 INFO - PROCESS | 25542 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:10:42 INFO - PROCESS | 25542 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:10:42 INFO - PROCESS | 25542 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:10:42 INFO - PROCESS | 25542 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:10:42 INFO - PROCESS | 25542 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:10:42 INFO - PROCESS | 25542 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:10:42 INFO - PROCESS | 25542 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:10:42 INFO - PROCESS | 25542 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:10:42 INFO - PROCESS | 25542 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:10:42 INFO - PROCESS | 25542 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:10:42 INFO - PROCESS | 25542 | 08:10:42 INFO - PROCESS | 25542 | ************************* 08:12:02 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-ext.html (next: http://localhost:35693/tests/dromaeo/cssquery-jquery.html) 08:12:02 INFO - PROCESS | 25542 | RSS: Main: 244494336 08:12:02 INFO - PROCESS | 25542 | 08:15:04 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35693/tests/dromaeo/cssquery-mootools.html) 08:15:05 INFO - PROCESS | 25542 | RSS: Main: 213344256 08:15:05 INFO - PROCESS | 25542 | 08:18:07 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35693/tests/dromaeo/cssquery-prototype.html) 08:18:08 INFO - PROCESS | 25542 | RSS: Main: 203440128 08:18:08 INFO - PROCESS | 25542 | 08:21:11 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35693/tests/dromaeo/cssquery-yui.html) 08:21:12 INFO - PROCESS | 25542 | RSS: Main: 189263872 08:21:12 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | Cycle 1(1): loaded http://localhost:35693/tests/dromaeo/cssquery-yui.html (next: http://localhost:35693/tests/dromaeo/cssquery-dojo.html) 08:24:15 INFO - PROCESS | 25542 | RSS: Main: 282226688 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | __start_tp_report 08:24:15 INFO - PROCESS | 25542 | _x_x_mozilla_page_load 08:24:15 INFO - PROCESS | 25542 | _x_x_mozilla_page_load_details 08:24:15 INFO - PROCESS | 25542 | |i|pagename|runs| 08:24:15 INFO - PROCESS | 25542 | |0;dojo.html;2548.681414823147;2565.999260288489;2598.974010259898;2600.2459286806825;2605.1142611512096;4543.159515489637;4561.657875659323;4662.976685116574;4665.9300110498325;4677.859664210071;4642.419697537808;4655.394798676176;4725.740084295371;4742.857714268577;4767.261074533444;4299.763513006784;4328.026194106316;4344.565543445662;4357.259265924785;4423;4415;4426.889327766799;4427.734335939837;4495.617872480843;4496.7751612419415;4290.656747460196;4328.069465064995;4360;4401.987542865127;4430.136123455927;4343.370211319341;4350.847720329805;4354.934675979863;4391.648668106575;4398.64810815134;4359.673024523174;4360.4331436913;4364.498082720496;4367.868963931087;4372.737635741865;4640.744759038252;4657.697249678794;4661.230896902007;4671.089137618166;4734.431868175841;5028.522290382408;5062.341895553592;5063.9093060919395;5072.289879416885;5105.617078719074;4970;5082.822101226477;5096.286519887219;5101;5152.458991805844;4626.884327891762;4654.953450465519;4676.2985552166765;4678.251479763221;4684.227102528044;5175.404828444702;5256.395514515803;5261.000409922102;5263.499967503081;5271.156614941668;4948.4309304429735;5011.799528018912;5015.172496538104;5030.2957585938;5048.671836330626;4911.484294149134;5091.745412729349;5097.847064587994;5113.8721531962;5132.769025393866;4901.215805471179;4946.183879659888;4956.058348913767;5026.874328141826;5056.443791182967;5050.368703912011;5055.039542486916;5055.064813009605;5056.216286475602;5103.132467480537;4322.438083049184;4323.913521729548;4326.091520780671;4352.216601011785;4361.996740749645;3645.690116340141;3679;3683.355412802749;3735.4210097434943;3748.5126933498473;4410.117976404732;4418;4450.1989641864775;4453.487848897354;4454.242778727624;5096.362954630671;5098.5411312982005;5106.770195341144;5107.821226257063;5112.130937740593;4263.147370525845;4298.032942587955;4316.417283666665;4410.25025745624;4436.334549817553;4490.730556166641;4509.2559727644575;4513.232750432434;4537.115262523776;4556.863294101182;4720.032393359422;4725.598324142349;4728.314394412722;4734.26618874075;4782.187028205144;4865.148598995091;4955.776990035385;5003.799848006039;5005.649604527648;5049.444561098349;4702.0360826031265;4733.219018861849;4743.051389722001;4764.714117152982;4801.975990120027;4970.701757894538;4975.701457912537;4976.552110310089;4976.676516026447;4979.452260251442;4901.730404827768;4936.185529387754;4987.576056035133;4989.525995030466;4998.050370429607;4892.510748925221;4932.679375840559;4935.4570997190995;4946.950530494649;4978.502149784992;4933.383327084114;4941.728204948762;4942.82700105509;4946.258061290835;4991.301217829434;4701.412323459567;4738.07607516531;4740.2178640523925;4885.193942999365;4891.5353041461;4855.150348689036;4874.073925954058;4875.195592727302;4877.073356062337;4878.707277563358;4680.812767489262;4735.12400205963;4741.525847415231;4742.78657460422;4750.311204875205 08:24:15 INFO - PROCESS | 25542 | |1;ext.html;505.0958783777039;517.8782985998292;518.5695872425886;524.2189138184104;527.4540850220023;26677.466450670974;35601.46597801032;37560.436593451086;37564.62435375645;40976.590234097726;38085.23829523408;38454.42318365227;38473.80763096188;38476;38543.42184867229;7411.962940185292;7623.733169339061;7780.455368124233;7787.9610601946915;7789.260020298063;7449.925500745005;9692.563834627423;9937.652182173626;9944.154746846527;10222.48887555623;9888.703338899844;9934.850977235346;9947.71202623397;9948.303618746691;9951.850722239173;9788.36375635585;9798;9863;9866.309358344919;9889.950550247275;9563.282753793494;9621.567029483691;9637.518124093767;9638.759031024281;9639.18066964302;36014.27971440583;36396.0900977475;36405.27189456196;36426.817865910765;36458.45312320317;3598.964010359889;3738.7569807962395;3745.0450135215347;3771.1891943232063;3805.0106972187154;27687.030953916717;27692.584611230846;27972.860135699193;28174.859125704446;28686.713132868823;3279.0818570800216;3296.307775367176;3306.8842590509457;3342.0308110647884;3359.7144242739396;223940;224400;224925;225615;227499;50965.745171273906;51293.74353128285;51341.7432912833;51445;51507.74246128745;54058.729706351216;54135.458645413826;54161.72919135379;54175.45824541783;54181.1872821908;12562.434690438951;12922.870771292166;12973;12975.935120324339;12999.220046797222;8571.485710857369;8645.913540864636;9525.857112143323;9590.808183836423;10374.636887708893;15535.766963495556;15539.766903496457;15609;15702;15844.207789610704;5377.516023557899;5639.436056394327;5656.8969051034655;5684.971575142098;5783.826485205451;786.9763907082797;790.7904405332591;798.8242586630931;804.5896592737746;810.1614828652316;25874.74125258723;27011.459770804868;27199.1840244793;27386.726132738815;27421.588676169875;774.0981756254014;779.7972527142983;781.7811012916393;805.3355981315439;1064.414571985405;14121.364538595788;14505.564833055025;14519;14720.190389528465;14768.261586920611;339023;339597;340222;340247;342441;47220.7638961803;47235.29147062797;47291;47342.526574734504;47360.7631961838;1186.46016062693;1201.645514573203;1225.840640716701;1227.864934857165;1230.6308107567515;24325.75674243235;25243.74756252414;25403;26329.736702633494;26533;40204.19591608093;40633.1873362537;40691;40725;40754.38868417036;222099;222758;222867;223316;224713;29705.5544166842;33253.16867078342;34994.650053499136;35819.82090089533;35862.46206306855;39407;39671.80164099161;39859.60140398559;39872.2025559493;40064.3990340151;34031.65968340384;34303.82848085744;34484.82757586196;34520.654793451744;35115.473267901514;34193.65806341905;34266.31467370688;34471.82764086164;34505.309893802485;34847;33707.15732106717;34129.82935085408;34209.31581368409;34443.827780860935;34494.310113798085;28321.43357132887;31197.37605247837;31271.218219544695;31325.84337078391;31349.373012539167;23308.883455582614;23342.766572334058;23660.408489787893;23918.5472198314;24091.638625420983 08:24:15 INFO - PROCESS | 25542 | |2;jquery.html;351.9718422526198;377.7506845481981;379.7209051347259;379.92401519696057;404.953430355509;12679.429425675844;15518.922405387972;15913.920430397846;16349.83650163498;17281.13594320285;16654.1672916354;16788.496345109637;16973.83026169741;17101.144942752842;17123.886947348412;4862.951370486294;4920.483349248331;5031.849044528652;5051.595872330205;5082.669626474286;3935.8425662973445;4119.443875076856;4993.375828021497;5076.187809950408;5691.260136182311;5925.762969481216;6043.788467403641;6047.485963693091;6049.274087109553;6050.576459647827;5829.591928564981;5983.670898100603;6019.729112189962;6031.427014433666;6038.96980515099;5328.254044433782;5328.600354973392;5333.679979201261;5335.466453354634;5336.119540275849;20010.499737506532;20079.598408031758;20104.89947550253;20129.79870201294;20198.667219908697;2180.4298220757346;2311.3874823171873;2316.073570571768;2333.57995560799;2384.2727967969763;12816.743665126738;13119.409626566732;13136.080474366798;13492.460301588022;13590.32048397586;1699;1729.44657709533;1755.8946463212249;1759.4369801663472;1780.5548612846787;62066.37933620696;63157.36842631561;63295.683521582556;64034.67982660057;64662.353376466104;9568.330216884891;9766.853497197548;9835.901740920675;9881.25890558211;9981.55820659814;13427.328633568282;13432.19406835593;13436.93281533586;13439.260840653858;13442.126261793148;6787.457003439713;6931.341522555349;7027.051348067946;7029.5430796998035;7141.428685705235;6442.452391546677;6505.707243174069;6597.1423714916755;6615.6030638161865;6643.534952553275;5874.500667443315;6081.483073938764;6093.6039157454625;6125.632462052291;6217.285012223561;1738.9043602601726;1762.4536393717988;1773.6275382169888;1780.9821901781077;1815.1831675745966;820.6225136437187;823.4112609484248;825.195434451405;854.6666799948025;859.1537335724306;10256.58973641046;10436.530356133992;10747.731306717395;15990.36038558468;16582.17089145538;394.12504240585855;399.0602131979205;404.5166026598187;404.6661504258998;405.6308759029328;5366.141417373202;5522.585806064561;5531.723413829292;5556.722163891789;5779.768809247583;346023;349882;351441;356151;362872;68969.3103068973;69588.30411695818;70338.64830675814;70671;73002.63498682472;421.0925455643094;429.79155109771733;431.4412835378193;432.27593780417675;432.42703417971313;9595.616175352907;9675.080867317594;9708.805823883624;9784.80430391374;9923.950380248052;34511;34929.30141397208;35200.64799352076;35551.644483555865;35624.46563301501;46771.76614116908;47153.764231178626;47603.52396475991;47642.52357476381;47672;7936.6428510716005;7954;7982.9600851995365;7991.760247192594;8122.959385203036;11596;11848.28910265387;11868.881311186778;11958.282503049844;12404.441800119197;10258.384496930208;10332.586696532055;10361.11930485887;10801.513931873242;11039.779204416027;10075.748106297402;10419.374837509773;10436.478176091317;10576.471176440993;10594.31136976094;10180.541875615467;10186.236032297458;10493.685189444328;10552.577896884346;10778.676639700821;7716.421268405004;7729.574873382018;7766.145723970471;7782.49413788102;8105.959470202847;8292.668293268202;9878.703638890845;10297.227707921786;10411.895881041093;10416.114630256514 08:24:15 INFO - PROCESS | 25542 | |3;mootools.html;1229.1150371732356;1255.0649765924386;1262.760075585638;1266.6769973656722;1275.5280546197907;6654.933450665492;7011.789646310619;7016.579005259675;7031.683574239159;7060.5410648307825;6270.49836013119;6484.6433446160445;6494.675266236681;6892.310768923106;6903.723851045965;2054.445299769065;2117.3859580721573;2117.4494631395796;2117.4706323419146;2122.7558830734433;2030.3620238727358;2106.3891471473253;2114.6616541353387;2116.7248257726474;2116.947076323089;1819.681555727749;1893.9810601893942;2049.661805802041;2123.6071326804513;2130.307650013744;2040.0717673458614;2058.4853786553363;2059.5468996820678;2061.2579471390286;2062.04115086484;2011.2859934723256;2031.2179810772811;2040.3878836349036;2069.379186244121;2116.216999710102;6990.860182796367;7072.045273888062;7076.823079423004;7080.256573059857;7080.575165490036;4231;4336.219480493542;4368.235558777233;4368.694191406603;4369.169857727054;2678.7455191756753;2763.6545431821023;2901.970980290212;2904.2158617173454;2906.5640153977074;1011.3476807459148;1090.2967585907124;1135.3017893995134;1141.024424117376;1143.056977993159;8767.298616110696;9112.134347237003;9120.58957346921;9123.543822808899;9139.26885849137;3766.227923275722;3885.572587015426;3905.941410878839;3914.823832927468;3960.2673505401594;7495;7619.847603048019;7623.237676232332;7624.961875190699;7628.732994345282;4280;4284.571542845691;4291.82832686689;4293.828246870153;4294.742315461082;3940.408938659167;3998.8400463981116;4010.2981978153707;4018.1361007383553;4020.4773379460485;3064.2033071401597;3092.1187461573345;3138.0230454813777;3168.445522033635;3169.9366012679616;924.9583768730423;936.6534382278601;945.8864936207699;955.8661787349777;965.7682156282442;651.3486513486514;685.5646664368162;695.197047410242;701.7684164225793;725.8620862036196;4548.749818759934;4683.367745354401;4712.882177945578;4853.39332583427;5012.172991456442;833.1543483364389;834.7871292820413;835.3692961813913;840.9789755256045;843.5655637346771;3184.5541624172633;3363.24327026422;3473.9305213896087;3511.367953768346;3541.203229273393;7885;8320.209580089882;8336.333093352638;8341.20758527939;8347.332213423033;8320.043195032527;8323.250907418362;8324.458910170819;8331.125231850569;8337.249647531751;6278.654673992883;6513.413792758674;6575.243846957565;6689.69896354675;6706.631135287606;4774.976125119352;4872.366592342973;4934.210526315772;4968.22992436173;4991.251312303184;7189.784306470814;7223.711051557879;7262.419006479575;7280;7655.57894315818;7295.2340004298785;7326.597037163007;7370.889436658562;7388.889166662612;7398.371138453076;6137.324894261538;6483.675816209076;6685.364890335411;6739.359760822714;6759.763408280686;6648.468122550088;6755.898661520179;6759.121314229315;6782.728690852311;6855.040294358694;5822.621529600562;5838.4453476919625;5868.911966320423;5897.262842144732;5915.142304365929;5780.335261444988;5808.099744539603;5847.707614619167;5856.853578660568;5902.881942361043;5449.618526703092;5493.533049690821;5561.165825126263;5585.609007369445;5599.440055994368;4000.6199411055904;4093.2836753567412;4117.855875044359;4239.279322515242;4251.3622956556765;4037.9798101009305;4045.9797701011307;4164.437800896962;4248.532661407181;4256.936145957875 08:24:15 INFO - PROCESS | 25542 | |4;prototype.html;310.9067279816056;328.80436140496414;332.87018062955434;333.5646980690199;338.5750882642284;4767.785449654765;4799.496052914446;4827.662063655545;4848.369711937447;4858.465568787439;4503.581371867867;4551.2717965125585;4628.375169352136;4652.883677908054;4774.11678839415;3824.694024478049;3824.8661296854616;3837.884863454101;3840.4431357453154;3847;3840.865569705061;3870.748401353903;3873.7797593757878;3879.2241551689635;3883.2233553289316;3632.6367363263594;3678.3930651442615;3737.345964456223;3788.8931076517265;3837.9232415351817;3812.923741525182;3814.5422549293985;3815.1606646537716;3815.523059617534;3829.9617003830163;3448.3103379323984;3448.3620530201993;3449.862005519776;3450.7584469087174;3461.671141241578;4492.460904691426;4628.32889231063;4632.027274272406;4676.181668208084;4694.906101877943;4287.421198138242;4316.848910288125;4332.241857674926;4332.306830907071;4365.476142862845;4927.802887884481;4932.235503496964;4932.309476673268;4938.4814594467425;4995.625328100407;3737.495438115847;3751.643593858579;3761.774293542369;3771.208046310278;3791.6018818024263;4957.90084198314;5152.768125434327;5153.561947234527;5166.741662916839;5268.315119034501;3788.882279727487;3852.210296889131;3854.421836724514;3868.883933482;3872.8381485554223;3915.4126880968083;3917.177392747555;3925.430812532224;3932.665723413542;3936.586658400884;4145.54399016108;4331;4370.7159034662645;4380.671449641226;4386.890327741832;4948.1835497142565;5002.249662550574;5008.398992120896;5014.924776128361;5015.548600625888;1303.5046682260681;1306.490468717182;1307.8038294255748;1313.658448803318;1319.531566293971;867.9021038386446;909.9171985337427;916.6516723644972;961.8797650293714;967.7725734452398;4267.1465706858135;4275.46556680415;4287.614114729689;4288.31386978082;4297.290946993773;4956.677815941952;4995.050940321327;5015.648904576645;5025.6482046256415;5048.924266136011;4165.208610364021;4229.894252643709;4309.159713855768;4317.222899877989;4460.888477788081;2651.4564514274525;2661.4011847334195;2667.8532680702747;2683.449892771977;2694.5149873023042;4753.453352864465;4754.0016596515115;4761.666683332133;4775;4775.283707443911;4910.754462276872;5064.49355064498;5078.644494885322;5094.943034179462;5103.923441148386;4710.9764451177525;4725.220338644085;4731.999999999931;4735.976320118378;4749.762511874393;4996.300517927566;4997.200447928314;5003.273870514667;5009.524095210949;5013.724245166405;4680.987175514561;4900.4854490278;4918.213085906238;4963.503649635008;4972.552470277692;4723.929141062956;4728.3380326753595;4728.723244723974;4735.644826638085;4740.407449068866;3485.6165821759078;3489.3370259650587;3499.545059142295;3509.8596056157467;3511.1924257420424;4800.303955926302;5007.799688012438;5038.420581633159;5041.873953151201;5060.392752869532;4839.370881785346;4841.709497430024;4843.443004054438;4854.854354369375;4862.270659400977;4798.856034318976;4811.759412029454;4820.39745031871;4837.250226214942;4842.377803435747;4663.603593694574;4739.810407583658;4769.976150119227;4787.515870080286;4815.4109143981705;4870.878228044327;4885.853424397274;4887.462379138221;4890.657653964188;4894.545963173495;4718.315844202502;4746.098241334135;4748.7443464354155;4754.23932170851;4766.189747742823 08:24:15 INFO - PROCESS | 25542 | |5;yui.html;664.1299897134751;679.8572299817035;726.9745558905439;749.7713197474769;760.5284723471447;12259.264444133347;13117.672058198526;13123.54067607634;13218.999999999987;13266.137701049438;12932.224066556013;13098.541551045715;13116.606501804938;13117.34413279337;13143;656.684791300175;682.0315152483461;792.9167437419075;819.2258315891485;830.3523251863526;825.9545724985123;826.0541679776652;826.0747962282252;826.5412695953743;827.5613924619931;866.4541338956449;868.4615538366222;868.682930730282;868.7437206024207;868.9391742578022;701.5475018612991;728.2935552514053;834.6119054639622;861.3109512390125;869.1395518436765;631.1763149090435;631.9494440444753;632.11503894548;632.4402903430451;633.1484153813115;12861.292628905407;13569.52506662272;13625.591232263048;13626.931865340708;13644;11703.122265830098;11722.531098756039;11795.233309834834;11940.761184776256;12120.878791212062;10054.547545360476;10217;10342.379457232515;11479.655610331703;11622.6513204604;4121.9381709274385;4137.213929353444;4153.605407486284;4201.68487363449;4258.169656916872;56674.7166264166;57873.42126578765;59044.704776476705;59433;59862;9801.509924503745;9815.165710914509;9895.218307648622;9913.504324783731;10101.949490252502;17164.742528862076;17249.655006899793;17424.91287543554;17440.825591744175;17442.82557174412;6249.96875015622;6336.6711314488375;6368.808935731936;6440.194975628046;6491.772787952399;3594.2092739597247;3736.084659258445;3740.401535754321;3742.681872040907;3858.7298889078056;2828.4484525517737;2903.564465330175;2908.88364465419;2911.7670586353042;2914.7522460590662;471.1119539667751;478.8515560176287;482.9275608658729;488.0897126858384;490.0297411125919;560.7869009776259;566.5892228134569;573.9483446489836;578;595.4492094812281;7281.781546553612;7410.184879663233;7482.8198015843045;7509.66206520697;7534.698612055566;553.4659054012897;558.1181732862068;572.7451284178584;586.7946218823463;588.6703446070129;3630.4009838376373;3685.7419980601635;3721.6092310307567;3760.6427389397963;3855.20968201518;61756.38243617507;61797.382026180065;62166.0675089874;63963.36036639667;64325;55480.72259638757;55641.44358556443;56337.71831140818;56689.43310566924;57187.714061429426;51438.48561514337;51632;51678;51720.741396292775;52416.73791631017;6693.778524624449;6789.796306110825;6810.694247549045;6955.200151982587;7120.430365570638;30616.234594135323;32022.839885800422;34880.82559587186;35338.64661353456;36039;42344.57655423407;43560.34659480047;44442;44851.551484486045;46266.30600541062;9313.720588382359;9411.38969408986;9432.764180895532;9509.096635819586;9552.23582113443;10409.791804163722;10617.840732389175;12019.63941081769;12123.818142728043;12288.385580721108;12155.802244102177;12283.324417157142;12858;13390.062695611214;13963.860361396662;12232.816507752568;12358.50565977351;13057.738845222852;13272.203667779964;13658.931705341409;12763.74472510526;12827.166234194747;13071.411786469442;13381.732365352444;13589.388477518733;9829;10225.693229203136;10352.430616315874;10509.421981791074;10718.410487423267;9100.681476148304;10269.843332900175;10350.844737329096;10355.792884142124;10377.16982641402 08:24:15 INFO - PROCESS | 25542 | __end_tp_report 08:24:15 INFO - PROCESS | 25542 | __start_cc_report 08:24:15 INFO - PROCESS | 25542 | _x_x_mozilla_cycle_collect,1520 08:24:15 INFO - PROCESS | 25542 | __end_cc_report 08:24:15 INFO - PROCESS | 25542 | __startTimestamp1483115055394__endTimestamp 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | ------- Summary: start ------- 08:24:15 INFO - PROCESS | 25542 | Number of tests: 6 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#0] dojo.html Cycles:165 Average:4660.28 Median:4738.08 stddev:491.86 (10.4%) stddev-sans-first:464.63 08:24:15 INFO - PROCESS | 25542 | Values: 2548.7 2566.0 2599.0 2600.2 2605.1 4543.2 4561.7 4663.0 4665.9 4677.9 4642.4 4655.4 4725.7 4742.9 4767.3 4299.8 4328.0 4344.6 4357.3 4423.0 4415.0 4426.9 4427.7 4495.6 4496.8 4290.7 4328.1 4360.0 4402.0 4430.1 4343.4 4350.8 4354.9 4391.6 4398.6 4359.7 4360.4 4364.5 4367.9 4372.7 4640.7 4657.7 4661.2 4671.1 4734.4 5028.5 5062.3 5063.9 5072.3 5105.6 4970.0 5082.8 5096.3 5101.0 5152.5 4626.9 4655.0 4676.3 4678.3 4684.2 5175.4 5256.4 5261.0 5263.5 5271.2 4948.4 5011.8 5015.2 5030.3 5048.7 4911.5 5091.7 5097.8 5113.9 5132.8 4901.2 4946.2 4956.1 5026.9 5056.4 5050.4 5055.0 5055.1 5056.2 5103.1 4322.4 4323.9 4326.1 4352.2 4362.0 3645.7 3679.0 3683.4 3735.4 3748.5 4410.1 4418.0 4450.2 4453.5 4454.2 5096.4 5098.5 5106.8 5107.8 5112.1 4263.1 4298.0 4316.4 4410.3 4436.3 4490.7 4509.3 4513.2 4537.1 4556.9 4720.0 4725.6 4728.3 4734.3 4782.2 4865.1 4955.8 5003.8 5005.6 5049.4 4702.0 4733.2 4743.1 4764.7 4802.0 4970.7 4975.7 4976.6 4976.7 4979.5 4901.7 4936.2 4987.6 4989.5 4998.1 4892.5 4932.7 4935.5 4947.0 4978.5 4933.4 4941.7 4942.8 4946.3 4991.3 4701.4 4738.1 4740.2 4885.2 4891.5 4855.2 4874.1 4875.2 4877.1 4878.7 4680.8 4735.1 4741.5 4742.8 4750.3 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#1] ext.html Cycles:180 Average:42201.89 Median:19806.62 stddev:70303.94 (355.0%) stddev-sans-first:70431.45 08:24:15 INFO - PROCESS | 25542 | Values: 505.1 517.9 518.6 524.2 527.5 26677.5 35601.5 37560.4 37564.6 40976.6 38085.2 38454.4 38473.8 38476.0 38543.4 7412.0 7623.7 7780.5 7788.0 7789.3 7449.9 9692.6 9937.7 9944.2 10222.5 9888.7 9934.9 9947.7 9948.3 9951.9 9788.4 9798.0 9863.0 9866.3 9890.0 9563.3 9621.6 9637.5 9638.8 9639.2 36014.3 36396.1 36405.3 36426.8 36458.5 3599.0 3738.8 3745.0 3771.2 3805.0 27687.0 27692.6 27972.9 28174.9 28686.7 3279.1 3296.3 3306.9 3342.0 3359.7 223940.0 224400.0 224925.0 225615.0 227499.0 50965.7 51293.7 51341.7 51445.0 51507.7 54058.7 54135.5 54161.7 54175.5 54181.2 12562.4 12922.9 12973.0 12975.9 12999.2 8571.5 8645.9 9525.9 9590.8 10374.6 15535.8 15539.8 15609.0 15702.0 15844.2 5377.5 5639.4 5656.9 5685.0 5783.8 787.0 790.8 798.8 804.6 810.2 25874.7 27011.5 27199.2 27386.7 27421.6 774.1 779.8 781.8 805.3 1064.4 14121.4 14505.6 14519.0 14720.2 14768.3 339023.0 339597.0 340222.0 340247.0 342441.0 47220.8 47235.3 47291.0 47342.5 47360.8 1186.5 1201.6 1225.8 1227.9 1230.6 24325.8 25243.7 25403.0 26329.7 26533.0 40204.2 40633.2 40691.0 40725.0 40754.4 222099.0 222758.0 222867.0 223316.0 224713.0 29705.6 33253.2 34994.7 35819.8 35862.5 39407.0 39671.8 39859.6 39872.2 40064.4 34031.7 34303.8 34484.8 34520.7 35115.5 34193.7 34266.3 34471.8 34505.3 34847.0 33707.2 34129.8 34209.3 34443.8 34494.3 28321.4 31197.4 31271.2 31325.8 31349.4 23308.9 23342.8 23660.4 23918.5 24091.6 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#2] jquery.html Cycles:180 Average:22514.52 Median:425.44 stddev:58313.63 (13706.6%) stddev-sans-first:58453.48 08:24:15 INFO - PROCESS | 25542 | Values: 352.0 377.8 379.7 379.9 405.0 12679.4 15518.9 15913.9 16349.8 17281.1 16654.2 16788.5 16973.8 17101.1 17123.9 4863.0 4920.5 5031.8 5051.6 5082.7 3935.8 4119.4 4993.4 5076.2 5691.3 5925.8 6043.8 6047.5 6049.3 6050.6 5829.6 5983.7 6019.7 6031.4 6039.0 5328.3 5328.6 5333.7 5335.5 5336.1 20010.5 20079.6 20104.9 20129.8 20198.7 2180.4 2311.4 2316.1 2333.6 2384.3 12816.7 13119.4 13136.1 13492.5 13590.3 1699.0 1729.4 1755.9 1759.4 1780.6 62066.4 63157.4 63295.7 64034.7 64662.4 9568.3 9766.9 9835.9 9881.3 9981.6 13427.3 13432.2 13436.9 13439.3 13442.1 6787.5 6931.3 7027.1 7029.5 7141.4 6442.5 6505.7 6597.1 6615.6 6643.5 5874.5 6081.5 6093.6 6125.6 6217.3 1738.9 1762.5 1773.6 1781.0 1815.2 820.6 823.4 825.2 854.7 859.2 10256.6 10436.5 10747.7 15990.4 16582.2 394.1 399.1 404.5 404.7 405.6 5366.1 5522.6 5531.7 5556.7 5779.8 346023.0 349882.0 351441.0 356151.0 362872.0 68969.3 69588.3 70338.6 70671.0 73002.6 421.1 429.8 431.4 432.3 432.4 9595.6 9675.1 9708.8 9784.8 9924.0 34511.0 34929.3 35200.6 35551.6 35624.5 46771.8 47153.8 47603.5 47642.5 47672.0 7936.6 7954.0 7983.0 7991.8 8123.0 11596.0 11848.3 11868.9 11958.3 12404.4 10258.4 10332.6 10361.1 10801.5 11039.8 10075.7 10419.4 10436.5 10576.5 10594.3 10180.5 10186.2 10493.7 10552.6 10778.7 7716.4 7729.6 7766.1 7782.5 8106.0 8292.7 9878.7 10297.2 10411.9 10416.1 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#3] mootools.html Cycles:180 Average:4573.39 Median:5471.58 stddev:2421.78 (44.3%) stddev-sans-first:2415.53 08:24:15 INFO - PROCESS | 25542 | Values: 1229.1 1255.1 1262.8 1266.7 1275.5 6654.9 7011.8 7016.6 7031.7 7060.5 6270.5 6484.6 6494.7 6892.3 6903.7 2054.4 2117.4 2117.4 2117.5 2122.8 2030.4 2106.4 2114.7 2116.7 2116.9 1819.7 1894.0 2049.7 2123.6 2130.3 2040.1 2058.5 2059.5 2061.3 2062.0 2011.3 2031.2 2040.4 2069.4 2116.2 6990.9 7072.0 7076.8 7080.3 7080.6 4231.0 4336.2 4368.2 4368.7 4369.2 2678.7 2763.7 2902.0 2904.2 2906.6 1011.3 1090.3 1135.3 1141.0 1143.1 8767.3 9112.1 9120.6 9123.5 9139.3 3766.2 3885.6 3905.9 3914.8 3960.3 7495.0 7619.8 7623.2 7625.0 7628.7 4280.0 4284.6 4291.8 4293.8 4294.7 3940.4 3998.8 4010.3 4018.1 4020.5 3064.2 3092.1 3138.0 3168.4 3169.9 925.0 936.7 945.9 955.9 965.8 651.3 685.6 695.2 701.8 725.9 4548.7 4683.4 4712.9 4853.4 5012.2 833.2 834.8 835.4 841.0 843.6 3184.6 3363.2 3473.9 3511.4 3541.2 7885.0 8320.2 8336.3 8341.2 8347.3 8320.0 8323.3 8324.5 8331.1 8337.2 6278.7 6513.4 6575.2 6689.7 6706.6 4775.0 4872.4 4934.2 4968.2 4991.3 7189.8 7223.7 7262.4 7280.0 7655.6 7295.2 7326.6 7370.9 7388.9 7398.4 6137.3 6483.7 6685.4 6739.4 6759.8 6648.5 6755.9 6759.1 6782.7 6855.0 5822.6 5838.4 5868.9 5897.3 5915.1 5780.3 5808.1 5847.7 5856.9 5902.9 5449.6 5493.5 5561.2 5585.6 5599.4 4000.6 4093.3 4117.9 4239.3 4251.4 4038.0 4046.0 4164.4 4248.5 4256.9 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#4] prototype.html Cycles:180 Average:4127.32 Median:4702.94 stddev:1146.92 (24.4%) stddev-sans-first:1113.79 08:24:15 INFO - PROCESS | 25542 | Values: 310.9 328.8 332.9 333.6 338.6 4767.8 4799.5 4827.7 4848.4 4858.5 4503.6 4551.3 4628.4 4652.9 4774.1 3824.7 3824.9 3837.9 3840.4 3847.0 3840.9 3870.7 3873.8 3879.2 3883.2 3632.6 3678.4 3737.3 3788.9 3837.9 3812.9 3814.5 3815.2 3815.5 3830.0 3448.3 3448.4 3449.9 3450.8 3461.7 4492.5 4628.3 4632.0 4676.2 4694.9 4287.4 4316.8 4332.2 4332.3 4365.5 4927.8 4932.2 4932.3 4938.5 4995.6 3737.5 3751.6 3761.8 3771.2 3791.6 4957.9 5152.8 5153.6 5166.7 5268.3 3788.9 3852.2 3854.4 3868.9 3872.8 3915.4 3917.2 3925.4 3932.7 3936.6 4145.5 4331.0 4370.7 4380.7 4386.9 4948.2 5002.2 5008.4 5014.9 5015.5 1303.5 1306.5 1307.8 1313.7 1319.5 867.9 909.9 916.7 961.9 967.8 4267.1 4275.5 4287.6 4288.3 4297.3 4956.7 4995.1 5015.6 5025.6 5048.9 4165.2 4229.9 4309.2 4317.2 4460.9 2651.5 2661.4 2667.9 2683.4 2694.5 4753.5 4754.0 4761.7 4775.0 4775.3 4910.8 5064.5 5078.6 5094.9 5103.9 4711.0 4725.2 4732.0 4736.0 4749.8 4996.3 4997.2 5003.3 5009.5 5013.7 4681.0 4900.5 4918.2 4963.5 4972.6 4723.9 4728.3 4728.7 4735.6 4740.4 3485.6 3489.3 3499.5 3509.9 3511.2 4800.3 5007.8 5038.4 5041.9 5060.4 4839.4 4841.7 4843.4 4854.9 4862.3 4798.9 4811.8 4820.4 4837.3 4842.4 4663.6 4739.8 4770.0 4787.5 4815.4 4870.9 4885.9 4887.5 4890.7 4894.5 4718.3 4746.1 4748.7 4754.2 4766.2 08:24:15 INFO - PROCESS | 25542 | 08:24:15 INFO - PROCESS | 25542 | [#5] yui.html Cycles:180 Average:14407.57 Median:480.89 stddev:17670.18 (3674.5%) stddev-sans-first:17689.61 08:24:15 INFO - PROCESS | 25542 | Values: 664.1 679.9 727.0 749.8 760.5 12259.3 13117.7 13123.5 13219.0 13266.1 12932.2 13098.5 13116.6 13117.3 13143.0 656.7 682.0 792.9 819.2 830.4 826.0 826.1 826.1 826.5 827.6 866.5 868.5 868.7 868.7 868.9 701.5 728.3 834.6 861.3 869.1 631.2 631.9 632.1 632.4 633.1 12861.3 13569.5 13625.6 13626.9 13644.0 11703.1 11722.5 11795.2 11940.8 12120.9 10054.5 10217.0 10342.4 11479.7 11622.7 4121.9 4137.2 4153.6 4201.7 4258.2 56674.7 57873.4 59044.7 59433.0 59862.0 9801.5 9815.2 9895.2 9913.5 10101.9 17164.7 17249.7 17424.9 17440.8 17442.8 6250.0 6336.7 6368.8 6440.2 6491.8 3594.2 3736.1 3740.4 3742.7 3858.7 2828.4 2903.6 2908.9 2911.8 2914.8 471.1 478.9 482.9 488.1 490.0 560.8 566.6 573.9 578.0 595.4 7281.8 7410.2 7482.8 7509.7 7534.7 553.5 558.1 572.7 586.8 588.7 3630.4 3685.7 3721.6 3760.6 3855.2 61756.4 61797.4 62166.1 63963.4 64325.0 55480.7 55641.4 56337.7 56689.4 57187.7 51438.5 51632.0 51678.0 51720.7 52416.7 6693.8 6789.8 6810.7 6955.2 7120.4 30616.2 32022.8 34880.8 35338.6 36039.0 42344.6 43560.3 44442.0 44851.6 46266.3 9313.7 9411.4 9432.8 9509.1 9552.2 10409.8 10617.8 12019.6 12123.8 12288.4 12155.8 12283.3 12858.0 13390.1 13963.9 12232.8 12358.5 13057.7 13272.2 13658.9 12763.7 12827.2 13071.4 13381.7 13589.4 9829.0 10225.7 10352.4 10509.4 10718.4 9100.7 10269.8 10350.8 10355.8 10377.2 08:24:15 INFO - PROCESS | 25542 | -------- Summary: end -------- 08:24:15 INFO - PROCESS | 25542 | 08:24:16 INFO - TEST-INFO | 25542: exit 0 08:24:16 INFO - TEST-OK | dromaeo_css | took 1120823ms 08:24:16 INFO - TEST-START | kraken 08:24:16 INFO - operating with platform_type : linux_ 08:24:16 INFO - Initialising browser for kraken test... 08:24:16 INFO - TEST-INFO | started process 25844 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_lncy2/profile http://localhost:35693/getInfo.html) 08:24:16 INFO - PROCESS | 25844 | 1483115056803 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:24:20 INFO - PROCESS | 25844 | __metrics Screen width/height:1600/1200 08:24:20 INFO - PROCESS | 25844 | colorDepth:24 08:24:20 INFO - PROCESS | 25844 | Browser inner width/height: 1024/697 08:24:20 INFO - PROCESS | 25844 | __metrics 08:24:20 INFO - PROCESS | 25844 | 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" 08:24:20 INFO - PROCESS | 25844 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:21 INFO - TEST-INFO | 25844: exit 0 08:24:21 INFO - Browser initialized. 08:24:21 INFO - Running cycle 1/1 for kraken test... 08:24:21 INFO - TEST-INFO | started process 25924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_lncy2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:33 INFO - PROCESS | 25924 | RSS: Main: 151023616 08:24:33 INFO - PROCESS | 25924 | 08:24:36 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35693/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:37 INFO - PROCESS | 25924 | RSS: Main: 205492224 08:24:37 INFO - PROCESS | 25924 | 08:24:40 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35693/tests/kraken/driver.html?testName=audio-dft) 08:24:41 INFO - PROCESS | 25924 | RSS: Main: 184672256 08:24:41 INFO - PROCESS | 25924 | 08:24:44 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35693/tests/kraken/driver.html?testName=audio-fft) 08:24:44 INFO - PROCESS | 25924 | RSS: Main: 267505664 08:24:44 INFO - PROCESS | 25924 | 08:24:46 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35693/tests/kraken/driver.html?testName=audio-oscillator) 08:24:47 INFO - PROCESS | 25924 | RSS: Main: 177893376 08:24:47 INFO - PROCESS | 25924 | 08:24:49 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35693/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:24:50 INFO - PROCESS | 25924 | RSS: Main: 182226944 08:24:50 INFO - PROCESS | 25924 | 08:24:53 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35693/tests/kraken/driver.html?testName=imaging-darkroom) 08:24:54 INFO - PROCESS | 25924 | RSS: Main: 221179904 08:24:54 INFO - PROCESS | 25924 | 08:24:57 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35693/tests/kraken/driver.html?testName=imaging-desaturate) 08:24:58 INFO - PROCESS | 25924 | RSS: Main: 215236608 08:24:58 INFO - PROCESS | 25924 | 08:25:01 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35693/tests/kraken/driver.html?testName=json-parse-financial) 08:25:01 INFO - PROCESS | 25924 | RSS: Main: 216727552 08:25:01 INFO - PROCESS | 25924 | 08:25:03 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35693/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:04 INFO - PROCESS | 25924 | RSS: Main: 180195328 08:25:04 INFO - PROCESS | 25924 | 08:25:05 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:06 INFO - PROCESS | 25924 | RSS: Main: 188657664 08:25:06 INFO - PROCESS | 25924 | 08:25:08 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:25:09 INFO - PROCESS | 25924 | RSS: Main: 196263936 08:25:09 INFO - PROCESS | 25924 | 08:25:12 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:12 INFO - PROCESS | 25924 | RSS: Main: 206745600 08:25:12 INFO - PROCESS | 25924 | 08:25:15 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:16 INFO - PROCESS | 25924 | RSS: Main: 202027008 08:25:16 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | Cycle 1(1): loaded http://localhost:35693/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35693/tests/kraken/driver.html?testName=ai-astar) 08:25:18 INFO - PROCESS | 25924 | RSS: Main: 253648896 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | __start_tp_report 08:25:18 INFO - PROCESS | 25924 | _x_x_mozilla_page_load 08:25:18 INFO - PROCESS | 25924 | _x_x_mozilla_page_load_details 08:25:18 INFO - PROCESS | 25924 | |i|pagename|runs| 08:25:18 INFO - PROCESS | 25924 | |0;ai-astar;106;116;116;119;115;118;115;115;117;118 08:25:18 INFO - PROCESS | 25924 | |1;audio-beat-detection;153;127;129;163;126;126;129;137;154;126 08:25:18 INFO - PROCESS | 25924 | |2;audio-dft;161;161;160;161;161;162;161;162;162;161 08:25:18 INFO - PROCESS | 25924 | |3;audio-fft;75;76;76;100;75;76;77;76;95;76 08:25:18 INFO - PROCESS | 25924 | |4;audio-oscillator;85;86;112;85;86;86;99;86;85;109 08:25:18 INFO - PROCESS | 25924 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 08:25:18 INFO - PROCESS | 25924 | |6;imaging-darkroom;151;149;149;149;149;149;149;149;149;149 08:25:18 INFO - PROCESS | 25924 | |7;imaging-desaturate;95;96;96;96;96;95;96;95;95;95 08:25:18 INFO - PROCESS | 25924 | |8;json-parse-financial;64;63;63;62;63;64;63;64;63;63 08:25:18 INFO - PROCESS | 25924 | |9;json-stringify-tinderbox;67;66;67;67;68;66;66;68;67;67 08:25:18 INFO - PROCESS | 25924 | |10;stanford-crypto-aes;85;93;88;84;85;84;84;84;91;84 08:25:18 INFO - PROCESS | 25924 | |11;stanford-crypto-ccm;135;135;142;132;132;139;141;131;184;143 08:25:18 INFO - PROCESS | 25924 | |12;stanford-crypto-pbkdf2;186;173;179;174;174;174;175;176;175;173 08:25:18 INFO - PROCESS | 25924 | |13;stanford-crypto-sha256-iterative;69;68;68;69;69;69;75;68;67;67 08:25:18 INFO - PROCESS | 25924 | __end_tp_report 08:25:18 INFO - PROCESS | 25924 | __start_cc_report 08:25:18 INFO - PROCESS | 25924 | _x_x_mozilla_cycle_collect,1808 08:25:18 INFO - PROCESS | 25924 | __end_cc_report 08:25:18 INFO - PROCESS | 25924 | __startTimestamp1483115118593__endTimestamp 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | ------- Summary: start ------- 08:25:18 INFO - PROCESS | 25924 | Number of tests: 14 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.50 stddev:3.63 (3.1%) stddev-sans-first:1.51 08:25:18 INFO - PROCESS | 25924 | Values: 106.0 116.0 116.0 119.0 115.0 118.0 115.0 115.0 117.0 118.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:133.00 stddev:14.19 (10.7%) stddev-sans-first:13.82 08:25:18 INFO - PROCESS | 25924 | Values: 153.0 127.0 129.0 163.0 126.0 126.0 129.0 137.0 154.0 126.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 08:25:18 INFO - PROCESS | 25924 | Values: 161.0 161.0 160.0 161.0 161.0 162.0 161.0 162.0 162.0 161.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#3] audio-fft Cycles:10 Average:80.20 Median:76.00 stddev:9.21 (12.1%) stddev-sans-first:9.58 08:25:18 INFO - PROCESS | 25924 | Values: 75.0 76.0 76.0 100.0 75.0 76.0 77.0 76.0 95.0 76.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:86.00 stddev:10.69 (12.4%) stddev-sans-first:11.05 08:25:18 INFO - PROCESS | 25924 | Values: 85.0 86.0 112.0 85.0 86.0 86.0 99.0 86.0 85.0 109.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 08:25:18 INFO - PROCESS | 25924 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.00 08:25:18 INFO - PROCESS | 25924 | Values: 151.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:25:18 INFO - PROCESS | 25924 | Values: 95.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 08:25:18 INFO - PROCESS | 25924 | Values: 64.0 63.0 63.0 62.0 63.0 64.0 63.0 64.0 63.0 63.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 08:25:18 INFO - PROCESS | 25924 | Values: 67.0 66.0 67.0 67.0 68.0 66.0 66.0 68.0 67.0 67.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.00 stddev:3.33 (3.9%) stddev-sans-first:3.50 08:25:18 INFO - PROCESS | 25924 | Values: 85.0 93.0 88.0 84.0 85.0 84.0 84.0 84.0 91.0 84.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:140.00 stddev:15.60 (11.1%) stddev-sans-first:16.37 08:25:18 INFO - PROCESS | 25924 | Values: 135.0 135.0 142.0 132.0 132.0 139.0 141.0 131.0 184.0 143.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:175.00 stddev:3.96 (2.3%) stddev-sans-first:1.86 08:25:18 INFO - PROCESS | 25924 | Values: 186.0 173.0 179.0 174.0 174.0 174.0 175.0 176.0 175.0 173.0 08:25:18 INFO - PROCESS | 25924 | 08:25:18 INFO - PROCESS | 25924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:2.28 (3.3%) stddev-sans-first:2.42 08:25:18 INFO - PROCESS | 25924 | Values: 69.0 68.0 68.0 69.0 69.0 69.0 75.0 68.0 67.0 67.0 08:25:18 INFO - PROCESS | 25924 | -------- Summary: end -------- 08:25:18 INFO - PROCESS | 25924 | 08:25:19 INFO - TEST-INFO | 25924: exit 0 08:25:19 INFO - TEST-OK | kraken | took 63471ms 08:25:19 INFO - SUITE-END | took 1184s 08:25:20 INFO - Completed test suite (00:19:44) 08:25:20 INFO - javascript benchmark 08:25:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2548.681414823147, 2565.999260288489, 2598.974010259898, 2600.2459286806825, 2605.1142611512096, 4543.159515489637, 4561.657875659323, 4662.976685116574, 4665.9300110498325, 4677.859664210071, 4642.419697537808, 4655.394798676176, 4725.740084295371, 4742.857714268577, 4767.261074533444, 4299.763513006784, 4328.026194106316, 4344.565543445662, 4357.259265924785, 4423.0, 4415.0, 4426.889327766799, 4427.734335939837, 4495.617872480843, 4496.7751612419415, 4290.656747460196, 4328.069465064995, 4360.0, 4401.987542865127, 4430.136123455927, 4343.370211319341, 4350.847720329805, 4354.934675979863, 4391.648668106575, 4398.64810815134, 4359.673024523174, 4360.4331436913, 4364.498082720496, 4367.868963931087, 4372.737635741865, 4640.744759038252, 4657.697249678794, 4661.230896902007, 4671.089137618166, 4734.431868175841, 5028.522290382408, 5062.341895553592, 5063.9093060919395, 5072.289879416885, 5105.617078719074, 4970.0, 5082.822101226477, 5096.286519887219, 5101.0, 5152.458991805844, 4626.884327891762, 4654.953450465519, 4676.2985552166765, 4678.251479763221, 4684.227102528044, 5175.404828444702, 5256.395514515803, 5261.000409922102, 5263.499967503081, 5271.156614941668, 4948.4309304429735, 5011.799528018912, 5015.172496538104, 5030.2957585938, 5048.671836330626, 4911.484294149134, 5091.745412729349, 5097.847064587994, 5113.8721531962, 5132.769025393866, 4901.215805471179, 4946.183879659888, 4956.058348913767, 5026.874328141826, 5056.443791182967, 5050.368703912011, 5055.039542486916, 5055.064813009605, 5056.216286475602, 5103.132467480537, 4322.438083049184, 4323.913521729548, 4326.091520780671, 4352.216601011785, 4361.996740749645, 3645.690116340141, 3679.0, 3683.355412802749, 3735.4210097434943, 3748.5126933498473, 4410.117976404732, 4418.0, 4450.1989641864775, 4453.487848897354, 4454.242778727624, 5096.362954630671, 5098.5411312982005, 5106.770195341144, 5107.821226257063, 5112.130937740593, 4263.147370525845, 4298.032942587955, 4316.417283666665, 4410.25025745624, 4436.334549817553, 4490.730556166641, 4509.2559727644575, 4513.232750432434, 4537.115262523776, 4556.863294101182, 4720.032393359422, 4725.598324142349, 4728.314394412722, 4734.26618874075, 4782.187028205144, 4865.148598995091, 4955.776990035385, 5003.799848006039, 5005.649604527648, 5049.444561098349, 4702.0360826031265, 4733.219018861849, 4743.051389722001, 4764.714117152982, 4801.975990120027, 4970.701757894538, 4975.701457912537, 4976.552110310089, 4976.676516026447, 4979.452260251442, 4901.730404827768, 4936.185529387754, 4987.576056035133, 4989.525995030466, 4998.050370429607, 4892.510748925221, 4932.679375840559, 4935.4570997190995, 4946.950530494649, 4978.502149784992, 4933.383327084114, 4941.728204948762, 4942.82700105509, 4946.258061290835, 4991.301217829434, 4701.412323459567, 4738.07607516531, 4740.2178640523925, 4885.193942999365, 4891.5353041461, 4855.150348689036, 4874.073925954058, 4875.195592727302, 4877.073356062337, 4878.707277563358, 4680.812767489262, 4735.12400205963, 4741.525847415231, 4742.78657460422, 4750.311204875205], "value": 4628.174498669716, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.0958783777039, 517.8782985998292, 518.5695872425886, 524.2189138184104, 527.4540850220023, 26677.466450670974, 35601.46597801032, 37560.436593451086, 37564.62435375645, 40976.590234097726, 38085.23829523408, 38454.42318365227, 38473.80763096188, 38476.0, 38543.42184867229, 7411.962940185292, 7623.733169339061, 7780.455368124233, 7787.9610601946915, 7789.260020298063, 7449.925500745005, 9692.563834627423, 9937.652182173626, 9944.154746846527, 10222.48887555623, 9888.703338899844, 9934.850977235346, 9947.71202623397, 9948.303618746691, 9951.850722239173, 9788.36375635585, 9798.0, 9863.0, 9866.309358344919, 9889.950550247275, 9563.282753793494, 9621.567029483691, 9637.518124093767, 9638.759031024281, 9639.18066964302, 36014.27971440583, 36396.0900977475, 36405.27189456196, 36426.817865910765, 36458.45312320317, 3598.964010359889, 3738.7569807962395, 3745.0450135215347, 3771.1891943232063, 3805.0106972187154, 27687.030953916717, 27692.584611230846, 27972.860135699193, 28174.859125704446, 28686.713132868823, 3279.0818570800216, 3296.307775367176, 3306.8842590509457, 3342.0308110647884, 3359.7144242739396, 223940.0, 224400.0, 224925.0, 225615.0, 227499.0, 50965.745171273906, 51293.74353128285, 51341.7432912833, 51445.0, 51507.74246128745, 54058.729706351216, 54135.458645413826, 54161.72919135379, 54175.45824541783, 54181.1872821908, 12562.434690438951, 12922.870771292166, 12973.0, 12975.935120324339, 12999.220046797222, 8571.485710857369, 8645.913540864636, 9525.857112143323, 9590.808183836423, 10374.636887708893, 15535.766963495556, 15539.766903496457, 15609.0, 15702.0, 15844.207789610704, 5377.516023557899, 5639.436056394327, 5656.8969051034655, 5684.971575142098, 5783.826485205451, 786.9763907082797, 790.7904405332591, 798.8242586630931, 804.5896592737746, 810.1614828652316, 25874.74125258723, 27011.459770804868, 27199.1840244793, 27386.726132738815, 27421.588676169875, 774.0981756254014, 779.7972527142983, 781.7811012916393, 805.3355981315439, 1064.414571985405, 14121.364538595788, 14505.564833055025, 14519.0, 14720.190389528465, 14768.261586920611, 339023.0, 339597.0, 340222.0, 340247.0, 342441.0, 47220.7638961803, 47235.29147062797, 47291.0, 47342.526574734504, 47360.7631961838, 1186.46016062693, 1201.645514573203, 1225.840640716701, 1227.864934857165, 1230.6308107567515, 24325.75674243235, 25243.74756252414, 25403.0, 26329.736702633494, 26533.0, 40204.19591608093, 40633.1873362537, 40691.0, 40725.0, 40754.38868417036, 222099.0, 222758.0, 222867.0, 223316.0, 224713.0, 29705.5544166842, 33253.16867078342, 34994.650053499136, 35819.82090089533, 35862.46206306855, 39407.0, 39671.80164099161, 39859.60140398559, 39872.2025559493, 40064.3990340151, 34031.65968340384, 34303.82848085744, 34484.82757586196, 34520.654793451744, 35115.473267901514, 34193.65806341905, 34266.31467370688, 34471.82764086164, 34505.309893802485, 34847.0, 33707.15732106717, 34129.82935085408, 34209.31581368409, 34443.827780860935, 34494.310113798085, 28321.43357132887, 31197.37605247837, 31271.218219544695, 31325.84337078391, 31349.373012539167, 23308.883455582614, 23342.766572334058, 23660.408489787893, 23918.5472198314, 24091.638625420983], "value": 16989.9678961576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9718422526198, 377.7506845481981, 379.7209051347259, 379.92401519696057, 404.953430355509, 12679.429425675844, 15518.922405387972, 15913.920430397846, 16349.83650163498, 17281.13594320285, 16654.1672916354, 16788.496345109637, 16973.83026169741, 17101.144942752842, 17123.886947348412, 4862.951370486294, 4920.483349248331, 5031.849044528652, 5051.595872330205, 5082.669626474286, 3935.8425662973445, 4119.443875076856, 4993.375828021497, 5076.187809950408, 5691.260136182311, 5925.762969481216, 6043.788467403641, 6047.485963693091, 6049.274087109553, 6050.576459647827, 5829.591928564981, 5983.670898100603, 6019.729112189962, 6031.427014433666, 6038.96980515099, 5328.254044433782, 5328.600354973392, 5333.679979201261, 5335.466453354634, 5336.119540275849, 20010.499737506532, 20079.598408031758, 20104.89947550253, 20129.79870201294, 20198.667219908697, 2180.4298220757346, 2311.3874823171873, 2316.073570571768, 2333.57995560799, 2384.2727967969763, 12816.743665126738, 13119.409626566732, 13136.080474366798, 13492.460301588022, 13590.32048397586, 1699.0, 1729.44657709533, 1755.8946463212249, 1759.4369801663472, 1780.5548612846787, 62066.37933620696, 63157.36842631561, 63295.683521582556, 64034.67982660057, 64662.353376466104, 9568.330216884891, 9766.853497197548, 9835.901740920675, 9881.25890558211, 9981.55820659814, 13427.328633568282, 13432.19406835593, 13436.93281533586, 13439.260840653858, 13442.126261793148, 6787.457003439713, 6931.341522555349, 7027.051348067946, 7029.5430796998035, 7141.428685705235, 6442.452391546677, 6505.707243174069, 6597.1423714916755, 6615.6030638161865, 6643.534952553275, 5874.500667443315, 6081.483073938764, 6093.6039157454625, 6125.632462052291, 6217.285012223561, 1738.9043602601726, 1762.4536393717988, 1773.6275382169888, 1780.9821901781077, 1815.1831675745966, 820.6225136437187, 823.4112609484248, 825.195434451405, 854.6666799948025, 859.1537335724306, 10256.58973641046, 10436.530356133992, 10747.731306717395, 15990.36038558468, 16582.17089145538, 394.12504240585855, 399.0602131979205, 404.5166026598187, 404.6661504258998, 405.6308759029328, 5366.141417373202, 5522.585806064561, 5531.723413829292, 5556.722163891789, 5779.768809247583, 346023.0, 349882.0, 351441.0, 356151.0, 362872.0, 68969.3103068973, 69588.30411695818, 70338.64830675814, 70671.0, 73002.63498682472, 421.0925455643094, 429.79155109771733, 431.4412835378193, 432.27593780417675, 432.42703417971313, 9595.616175352907, 9675.080867317594, 9708.805823883624, 9784.80430391374, 9923.950380248052, 34511.0, 34929.30141397208, 35200.64799352076, 35551.644483555865, 35624.46563301501, 46771.76614116908, 47153.764231178626, 47603.52396475991, 47642.52357476381, 47672.0, 7936.6428510716005, 7954.0, 7982.9600851995365, 7991.760247192594, 8122.959385203036, 11596.0, 11848.28910265387, 11868.881311186778, 11958.282503049844, 12404.441800119197, 10258.384496930208, 10332.586696532055, 10361.11930485887, 10801.513931873242, 11039.779204416027, 10075.748106297402, 10419.374837509773, 10436.478176091317, 10576.471176440993, 10594.31136976094, 10180.541875615467, 10186.236032297458, 10493.685189444328, 10552.577896884346, 10778.676639700821, 7716.421268405004, 7729.574873382018, 7766.145723970471, 7782.49413788102, 8105.959470202847, 8292.668293268202, 9878.703638890845, 10297.227707921786, 10411.895881041093, 10416.114630256514], "value": 7669.51105524184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.1150371732356, 1255.0649765924386, 1262.760075585638, 1266.6769973656722, 1275.5280546197907, 6654.933450665492, 7011.789646310619, 7016.579005259675, 7031.683574239159, 7060.5410648307825, 6270.49836013119, 6484.6433446160445, 6494.675266236681, 6892.310768923106, 6903.723851045965, 2054.445299769065, 2117.3859580721573, 2117.4494631395796, 2117.4706323419146, 2122.7558830734433, 2030.3620238727358, 2106.3891471473253, 2114.6616541353387, 2116.7248257726474, 2116.947076323089, 1819.681555727749, 1893.9810601893942, 2049.661805802041, 2123.6071326804513, 2130.307650013744, 2040.0717673458614, 2058.4853786553363, 2059.5468996820678, 2061.2579471390286, 2062.04115086484, 2011.2859934723256, 2031.2179810772811, 2040.3878836349036, 2069.379186244121, 2116.216999710102, 6990.860182796367, 7072.045273888062, 7076.823079423004, 7080.256573059857, 7080.575165490036, 4231.0, 4336.219480493542, 4368.235558777233, 4368.694191406603, 4369.169857727054, 2678.7455191756753, 2763.6545431821023, 2901.970980290212, 2904.2158617173454, 2906.5640153977074, 1011.3476807459148, 1090.2967585907124, 1135.3017893995134, 1141.024424117376, 1143.056977993159, 8767.298616110696, 9112.134347237003, 9120.58957346921, 9123.543822808899, 9139.26885849137, 3766.227923275722, 3885.572587015426, 3905.941410878839, 3914.823832927468, 3960.2673505401594, 7495.0, 7619.847603048019, 7623.237676232332, 7624.961875190699, 7628.732994345282, 4280.0, 4284.571542845691, 4291.82832686689, 4293.828246870153, 4294.742315461082, 3940.408938659167, 3998.8400463981116, 4010.2981978153707, 4018.1361007383553, 4020.4773379460485, 3064.2033071401597, 3092.1187461573345, 3138.0230454813777, 3168.445522033635, 3169.9366012679616, 924.9583768730423, 936.6534382278601, 945.8864936207699, 955.8661787349777, 965.7682156282442, 651.3486513486514, 685.5646664368162, 695.197047410242, 701.7684164225793, 725.8620862036196, 4548.749818759934, 4683.367745354401, 4712.882177945578, 4853.39332583427, 5012.172991456442, 833.1543483364389, 834.7871292820413, 835.3692961813913, 840.9789755256045, 843.5655637346771, 3184.5541624172633, 3363.24327026422, 3473.9305213896087, 3511.367953768346, 3541.203229273393, 7885.0, 8320.209580089882, 8336.333093352638, 8341.20758527939, 8347.332213423033, 8320.043195032527, 8323.250907418362, 8324.458910170819, 8331.125231850569, 8337.249647531751, 6278.654673992883, 6513.413792758674, 6575.243846957565, 6689.69896354675, 6706.631135287606, 4774.976125119352, 4872.366592342973, 4934.210526315772, 4968.22992436173, 4991.251312303184, 7189.784306470814, 7223.711051557879, 7262.419006479575, 7280.0, 7655.57894315818, 7295.2340004298785, 7326.597037163007, 7370.889436658562, 7388.889166662612, 7398.371138453076, 6137.324894261538, 6483.675816209076, 6685.364890335411, 6739.359760822714, 6759.763408280686, 6648.468122550088, 6755.898661520179, 6759.121314229315, 6782.728690852311, 6855.040294358694, 5822.621529600562, 5838.4453476919625, 5868.911966320423, 5897.262842144732, 5915.142304365929, 5780.335261444988, 5808.099744539603, 5847.707614619167, 5856.853578660568, 5902.881942361043, 5449.618526703092, 5493.533049690821, 5561.165825126263, 5585.609007369445, 5599.440055994368, 4000.6199411055904, 4093.2836753567412, 4117.855875044359, 4239.279322515242, 4251.3622956556765, 4037.9798101009305, 4045.9797701011307, 4164.437800896962, 4248.532661407181, 4256.936145957875], "value": 3748.469775141749, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.9067279816056, 328.80436140496414, 332.87018062955434, 333.5646980690199, 338.5750882642284, 4767.785449654765, 4799.496052914446, 4827.662063655545, 4848.369711937447, 4858.465568787439, 4503.581371867867, 4551.2717965125585, 4628.375169352136, 4652.883677908054, 4774.11678839415, 3824.694024478049, 3824.8661296854616, 3837.884863454101, 3840.4431357453154, 3847.0, 3840.865569705061, 3870.748401353903, 3873.7797593757878, 3879.2241551689635, 3883.2233553289316, 3632.6367363263594, 3678.3930651442615, 3737.345964456223, 3788.8931076517265, 3837.9232415351817, 3812.923741525182, 3814.5422549293985, 3815.1606646537716, 3815.523059617534, 3829.9617003830163, 3448.3103379323984, 3448.3620530201993, 3449.862005519776, 3450.7584469087174, 3461.671141241578, 4492.460904691426, 4628.32889231063, 4632.027274272406, 4676.181668208084, 4694.906101877943, 4287.421198138242, 4316.848910288125, 4332.241857674926, 4332.306830907071, 4365.476142862845, 4927.802887884481, 4932.235503496964, 4932.309476673268, 4938.4814594467425, 4995.625328100407, 3737.495438115847, 3751.643593858579, 3761.774293542369, 3771.208046310278, 3791.6018818024263, 4957.90084198314, 5152.768125434327, 5153.561947234527, 5166.741662916839, 5268.315119034501, 3788.882279727487, 3852.210296889131, 3854.421836724514, 3868.883933482, 3872.8381485554223, 3915.4126880968083, 3917.177392747555, 3925.430812532224, 3932.665723413542, 3936.586658400884, 4145.54399016108, 4331.0, 4370.7159034662645, 4380.671449641226, 4386.890327741832, 4948.1835497142565, 5002.249662550574, 5008.398992120896, 5014.924776128361, 5015.548600625888, 1303.5046682260681, 1306.490468717182, 1307.8038294255748, 1313.658448803318, 1319.531566293971, 867.9021038386446, 909.9171985337427, 916.6516723644972, 961.8797650293714, 967.7725734452398, 4267.1465706858135, 4275.46556680415, 4287.614114729689, 4288.31386978082, 4297.290946993773, 4956.677815941952, 4995.050940321327, 5015.648904576645, 5025.6482046256415, 5048.924266136011, 4165.208610364021, 4229.894252643709, 4309.159713855768, 4317.222899877989, 4460.888477788081, 2651.4564514274525, 2661.4011847334195, 2667.8532680702747, 2683.449892771977, 2694.5149873023042, 4753.453352864465, 4754.0016596515115, 4761.666683332133, 4775.0, 4775.283707443911, 4910.754462276872, 5064.49355064498, 5078.644494885322, 5094.943034179462, 5103.923441148386, 4710.9764451177525, 4725.220338644085, 4731.999999999931, 4735.976320118378, 4749.762511874393, 4996.300517927566, 4997.200447928314, 5003.273870514667, 5009.524095210949, 5013.724245166405, 4680.987175514561, 4900.4854490278, 4918.213085906238, 4963.503649635008, 4972.552470277692, 4723.929141062956, 4728.3380326753595, 4728.723244723974, 4735.644826638085, 4740.407449068866, 3485.6165821759078, 3489.3370259650587, 3499.545059142295, 3509.8596056157467, 3511.1924257420424, 4800.303955926302, 5007.799688012438, 5038.420581633159, 5041.873953151201, 5060.392752869532, 4839.370881785346, 4841.709497430024, 4843.443004054438, 4854.854354369375, 4862.270659400977, 4798.856034318976, 4811.759412029454, 4820.39745031871, 4837.250226214942, 4842.377803435747, 4663.603593694574, 4739.810407583658, 4769.976150119227, 4787.515870080286, 4815.4109143981705, 4870.878228044327, 4885.853424397274, 4887.462379138221, 4890.657653964188, 4894.545963173495, 4718.315844202502, 4746.098241334135, 4748.7443464354155, 4754.23932170851, 4766.189747742823], "value": 3776.0024949276108, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.1299897134751, 679.8572299817035, 726.9745558905439, 749.7713197474769, 760.5284723471447, 12259.264444133347, 13117.672058198526, 13123.54067607634, 13218.999999999987, 13266.137701049438, 12932.224066556013, 13098.541551045715, 13116.606501804938, 13117.34413279337, 13143.0, 656.684791300175, 682.0315152483461, 792.9167437419075, 819.2258315891485, 830.3523251863526, 825.9545724985123, 826.0541679776652, 826.0747962282252, 826.5412695953743, 827.5613924619931, 866.4541338956449, 868.4615538366222, 868.682930730282, 868.7437206024207, 868.9391742578022, 701.5475018612991, 728.2935552514053, 834.6119054639622, 861.3109512390125, 869.1395518436765, 631.1763149090435, 631.9494440444753, 632.11503894548, 632.4402903430451, 633.1484153813115, 12861.292628905407, 13569.52506662272, 13625.591232263048, 13626.931865340708, 13644.0, 11703.122265830098, 11722.531098756039, 11795.233309834834, 11940.761184776256, 12120.878791212062, 10054.547545360476, 10217.0, 10342.379457232515, 11479.655610331703, 11622.6513204604, 4121.9381709274385, 4137.213929353444, 4153.605407486284, 4201.68487363449, 4258.169656916872, 56674.7166264166, 57873.42126578765, 59044.704776476705, 59433.0, 59862.0, 9801.509924503745, 9815.165710914509, 9895.218307648622, 9913.504324783731, 10101.949490252502, 17164.742528862076, 17249.655006899793, 17424.91287543554, 17440.825591744175, 17442.82557174412, 6249.96875015622, 6336.6711314488375, 6368.808935731936, 6440.194975628046, 6491.772787952399, 3594.2092739597247, 3736.084659258445, 3740.401535754321, 3742.681872040907, 3858.7298889078056, 2828.4484525517737, 2903.564465330175, 2908.88364465419, 2911.7670586353042, 2914.7522460590662, 471.1119539667751, 478.8515560176287, 482.9275608658729, 488.0897126858384, 490.0297411125919, 560.7869009776259, 566.5892228134569, 573.9483446489836, 578.0, 595.4492094812281, 7281.781546553612, 7410.184879663233, 7482.8198015843045, 7509.66206520697, 7534.698612055566, 553.4659054012897, 558.1181732862068, 572.7451284178584, 586.7946218823463, 588.6703446070129, 3630.4009838376373, 3685.7419980601635, 3721.6092310307567, 3760.6427389397963, 3855.20968201518, 61756.38243617507, 61797.382026180065, 62166.0675089874, 63963.36036639667, 64325.0, 55480.72259638757, 55641.44358556443, 56337.71831140818, 56689.43310566924, 57187.714061429426, 51438.48561514337, 51632.0, 51678.0, 51720.741396292775, 52416.73791631017, 6693.778524624449, 6789.796306110825, 6810.694247549045, 6955.200151982587, 7120.430365570638, 30616.234594135323, 32022.839885800422, 34880.82559587186, 35338.64661353456, 36039.0, 42344.57655423407, 43560.34659480047, 44442.0, 44851.551484486045, 46266.30600541062, 9313.720588382359, 9411.38969408986, 9432.764180895532, 9509.096635819586, 9552.23582113443, 10409.791804163722, 10617.840732389175, 12019.63941081769, 12123.818142728043, 12288.385580721108, 12155.802244102177, 12283.324417157142, 12858.0, 13390.062695611214, 13963.860361396662, 12232.816507752568, 12358.50565977351, 13057.738845222852, 13272.203667779964, 13658.931705341409, 12763.74472510526, 12827.166234194747, 13071.411786469442, 13381.732365352444, 13589.388477518733, 9829.0, 10225.693229203136, 10352.430616315874, 10509.421981791074, 10718.410487423267, 9100.681476148304, 10269.843332900175, 10350.844737329096, 10355.792884142124, 10377.16982641402], "value": 6204.215039181712, "unit": "score"}], "name": "dromaeo_css", "value": 6128.193365022837, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 119.0, 115.0, 118.0, 115.0, 115.0, 117.0, 118.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 129.0, 163.0, 126.0, 126.0, 129.0, 137.0, 154.0, 126.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 100.0, 75.0, 76.0, 77.0, 76.0, 95.0, 76.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 112.0, 85.0, 86.0, 86.0, 99.0, 86.0, 85.0, 109.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 66.0, 68.0, 67.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 93.0, 88.0, 84.0, 85.0, 84.0, 84.0, 84.0, 91.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 135.0, 142.0, 132.0, 132.0, 139.0, 141.0, 131.0, 184.0, 143.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 173.0, 179.0, 174.0, 174.0, 174.0, 175.0, 176.0, 175.0, 173.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 69.0, 69.0, 69.0, 75.0, 68.0, 67.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1549.9000000000003, "alertThreshold": 2.0}]} 08:25:20 INFO - Return code: 0 08:25:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:20 INFO - # TBPL SUCCESS # 08:25:20 INFO - Running post-action listener: _resource_record_post_action 08:25:20 INFO - [mozharness: 2016-12-30 16:25:20.381548Z] Finished run-tests step (success) 08:25:20 INFO - Running post-run listener: _resource_record_post_run 08:25:20 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8192; Write bytes: 382488576; Read time: 188; Write time: 272400 08:25:20 INFO - TinderboxPrint: CPU usage
13.2% 08:25:20 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 188 08:25:20 INFO - TinderboxPrint: I/O write bytes / time
382,488,576 / 272,400 08:25:20 INFO - TinderboxPrint: CPU idle
8,323.8 (86.8%) 08:25:20 INFO - TinderboxPrint: CPU user
1,216.9 (12.7%) 08:25:20 INFO - TinderboxPrint: Swap in / out
0 / 0 08:25:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100130816; Read time: 0; Write time: 84076 08:25:20 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282357760; Read time: 184; Write time: 188324 08:25:21 INFO - Running post-run listener: _upload_blobber_files 08:25:21 INFO - Blob upload gear active. 08:25:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:21 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 08:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:25:21 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:25:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:25:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:22 INFO - (blobuploader) - INFO - Done attempting. 08:25:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:25 INFO - (blobuploader) - INFO - Done attempting. 08:25:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:25:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:25:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:26 INFO - (blobuploader) - INFO - Done attempting. 08:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:25:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:27 INFO - (blobuploader) - INFO - Done attempting. 08:25:27 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:27 INFO - Return code: 0 08:25:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ade0b351a9725650a789a7942fefc98a7c3c48c0388d0590fe1359892da571eab5f86b5c0b4119137ac684839b6fab151e89b0d0a679299b3f5693274d5d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b266c42eadb16869830b7fa9874089d53ace6f2615ba265c19fa5a884efc2d9a958f572db86a27441d1e13cf1fd2fccb71491e88c810034a319c3802a889c90e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1fd6c0a62b9a40a9108fd41ac1bcc0472b099e3f14aa5dba1b60d465faf549367f26e96fe4bdb76d1e8cc34a44feb96fc9ec732f7a5aaf120d043e2059f66b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46c99d91b51fd2a9b9682d86d41a308ae19c281b10243ff3e1393451aca332ea4c1f367ad2b80b3f9806e97287c21c42fa687533fcb49401bd77e8f011bb58b8"} 08:25:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:27 INFO - Contents: 08:25:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ade0b351a9725650a789a7942fefc98a7c3c48c0388d0590fe1359892da571eab5f86b5c0b4119137ac684839b6fab151e89b0d0a679299b3f5693274d5d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b266c42eadb16869830b7fa9874089d53ace6f2615ba265c19fa5a884efc2d9a958f572db86a27441d1e13cf1fd2fccb71491e88c810034a319c3802a889c90e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1fd6c0a62b9a40a9108fd41ac1bcc0472b099e3f14aa5dba1b60d465faf549367f26e96fe4bdb76d1e8cc34a44feb96fc9ec732f7a5aaf120d043e2059f66b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46c99d91b51fd2a9b9682d86d41a308ae19c281b10243ff3e1393451aca332ea4c1f367ad2b80b3f9806e97287c21c42fa687533fcb49401bd77e8f011bb58b8"} 08:25:27 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:27 INFO - Copying logs to upload dir... 08:25:27 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.439033 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-12-30 08:25:26.646493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:25:26.649897) ========= 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=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ade0b351a9725650a789a7942fefc98a7c3c48c0388d0590fe1359892da571eab5f86b5c0b4119137ac684839b6fab151e89b0d0a679299b3f5693274d5d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b266c42eadb16869830b7fa9874089d53ace6f2615ba265c19fa5a884efc2d9a958f572db86a27441d1e13cf1fd2fccb71491e88c810034a319c3802a889c90e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1fd6c0a62b9a40a9108fd41ac1bcc0472b099e3f14aa5dba1b60d465faf549367f26e96fe4bdb76d1e8cc34a44feb96fc9ec732f7a5aaf120d043e2059f66b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46c99d91b51fd2a9b9682d86d41a308ae19c281b10243ff3e1393451aca332ea4c1f367ad2b80b3f9806e97287c21c42fa687533fcb49401bd77e8f011bb58b8"} build_url:https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011374 build_url: 'https://queue.taskcluster.net/v1/task/ZS_dY6j3RZCX43CtCbjr8w/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b2ade0b351a9725650a789a7942fefc98a7c3c48c0388d0590fe1359892da571eab5f86b5c0b4119137ac684839b6fab151e89b0d0a679299b3f5693274d5d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b266c42eadb16869830b7fa9874089d53ace6f2615ba265c19fa5a884efc2d9a958f572db86a27441d1e13cf1fd2fccb71491e88c810034a319c3802a889c90e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c1fd6c0a62b9a40a9108fd41ac1bcc0472b099e3f14aa5dba1b60d465faf549367f26e96fe4bdb76d1e8cc34a44feb96fc9ec732f7a5aaf120d043e2059f66b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46c99d91b51fd2a9b9682d86d41a308ae19c281b10243ff3e1393451aca332ea4c1f367ad2b80b3f9806e97287c21c42fa687533fcb49401bd77e8f011bb58b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 08:25:26.676645) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:25:26.677036) ========= 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=31acecf42b6a79ec49471d4a000001bb-1483110474.18206-2020122492 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004450 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 08:25:26.759495) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:25:26.759875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 08:25:26.760222) ========= ========= Total master_lag: 0.17 =========