builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1481299613.44 results: success (0) buildid: 20161209064651 builduid: dcc1e70b0cc144088bce8afac7035cd1 revision: 8404d26166a35406f46ff237ed132735c98882b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.437024) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.437631) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.437964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.453620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.454005) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-09 08:06:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 24.8M=0s 2016-12-09 08:06:53 (24.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.582732) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.583088) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013282 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:53.632734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 08:06:53.633138) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8404d26166a35406f46ff237ed132735c98882b2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8404d26166a35406f46ff237ed132735c98882b2 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-09 08:06:53,716 truncating revision to first 12 chars 2016-12-09 08:06:53,716 Setting DEBUG logging. 2016-12-09 08:06:53,716 attempt 1/10 2016-12-09 08:06:53,716 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8404d26166a3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 08:06:55,063 unpacking tar archive at: mozilla-central-8404d26166a3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.663185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 08:06:55.312795) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:55.313275) ========= script_repo_revision: 8404d26166a35406f46ff237ed132735c98882b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:55.313976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:55.314580) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:06:55.329940) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-09 08:06:55.330361) ========= /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=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:06:55 INFO - MultiFileLogger online at 20161209 08:06:55 in /builds/slave/test 08:06:55 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:06:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:06:55 INFO - {'append_to_log': False, 08:06:55 INFO - 'base_work_dir': '/builds/slave/test', 08:06:55 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:06:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:06:55 INFO - 'branch': 'Firefox-Non-PGO', 08:06:55 INFO - 'buildbot_json_path': 'buildprops.json', 08:06:55 INFO - 'config_files': ('talos/linux_config.py',), 08:06:55 INFO - 'default_actions': ('clobber', 08:06:55 INFO - 'read-buildbot-config', 08:06:55 INFO - 'download-and-extract', 08:06:55 INFO - 'populate-webroot', 08:06:55 INFO - 'create-virtualenv', 08:06:55 INFO - 'install', 08:06:55 INFO - 'run-tests'), 08:06:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:06:55 INFO - 'download_minidump_stackwalk': True, 08:06:55 INFO - 'download_symbols': 'ondemand', 08:06:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:06:55 INFO - 'tooltool.py': '/tools/tooltool.py', 08:06:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:06:55 INFO - '/tools/misc-python/virtualenv.py')}, 08:06:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:06:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:06:55 INFO - 'installer_path': 'installer.exe', 08:06:55 INFO - 'log_level': 'info', 08:06:55 INFO - 'log_name': 'talos', 08:06:55 INFO - 'log_to_console': True, 08:06:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:06:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:06:55 INFO - 'opt_config_files': (), 08:06:55 INFO - 'pip_index': False, 08:06:55 INFO - 'sps_profile': False, 08:06:55 INFO - 'sps_profile_interval': 0, 08:06:55 INFO - 'suite': 'dromaeojs', 08:06:55 INFO - 'system_bits': '32', 08:06:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:06:55 INFO - 'title': 'talos-linux64-ix-004', 08:06:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:06:55 INFO - 'use_talos_json': True, 08:06:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:06:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:06:55 INFO - 'work_dir': 'build'} 08:06:55 INFO - [mozharness: 2016-12-09 16:06:55.512067Z] Running clobber step. 08:06:55 INFO - Running pre-action listener: _resource_record_pre_action 08:06:55 INFO - Running main action method: clobber 08:06:55 INFO - rmtree: /builds/slave/test/build 08:06:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:06:55 INFO - Running post-action listener: _resource_record_post_action 08:06:55 INFO - [mozharness: 2016-12-09 16:06:55.966563Z] Finished clobber step (success) 08:06:55 INFO - [mozharness: 2016-12-09 16:06:55.966662Z] Running read-buildbot-config step. 08:06:55 INFO - Running pre-action listener: _resource_record_pre_action 08:06:55 INFO - Running main action method: read_buildbot_config 08:06:55 INFO - Using buildbot properties: 08:06:55 INFO - { 08:06:55 INFO - "project": "", 08:06:55 INFO - "product": "firefox", 08:06:55 INFO - "script_repo_revision": "production", 08:06:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:06:55 INFO - "repository": "", 08:06:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:06:55 INFO - "buildid": "20161209064651", 08:06:55 INFO - "pgo_build": "False", 08:06:55 INFO - "basedir": "/builds/slave/test", 08:06:55 INFO - "buildnumber": 863, 08:06:55 INFO - "slavename": "talos-linux64-ix-004", 08:06:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:06:55 INFO - "platform": "ubuntu64_hw", 08:06:55 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:06:55 INFO - "branch": "mozilla-central", 08:06:55 INFO - "repo_path": "mozilla-central", 08:06:55 INFO - "revision": "8404d26166a35406f46ff237ed132735c98882b2", 08:06:55 INFO - "stage_platform": "linux64", 08:06:55 INFO - "builduid": "dcc1e70b0cc144088bce8afac7035cd1", 08:06:55 INFO - "slavebuilddir": "test" 08:06:55 INFO - } 08:06:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:06:55 INFO - Running post-action listener: _resource_record_post_action 08:06:55 INFO - [mozharness: 2016-12-09 16:06:55.969068Z] Finished read-buildbot-config step (success) 08:06:55 INFO - [mozharness: 2016-12-09 16:06:55.969155Z] Running download-and-extract step. 08:06:55 INFO - Running pre-action listener: _resource_record_pre_action 08:06:55 INFO - Running main action method: download_and_extract 08:06:55 INFO - mkdir: /builds/slave/test/build/tests 08:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:55 INFO - trying https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:06:55 INFO - Downloading https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/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:06:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/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:06:56 INFO - Downloaded 1361 bytes. 08:06:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:06:56 INFO - Using the following test package requirements: 08:06:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:06:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:06:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:06:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'jsshell-linux-x86_64.zip'], 08:06:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:06:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:06:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:06:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:06:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:06:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:06:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:06:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:06:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:06:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:06:56 INFO - Fetch https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:06:57 INFO - Expected file size: 20222410 08:06:57 INFO - Obtained file size: 20222410 08:07:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:07:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:07:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:07:01 INFO - Fetch https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:07:02 INFO - Expected file size: 20222410 08:07:02 INFO - Obtained file size: 20222410 08:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:07:06 INFO - Fetch https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:07:07 INFO - Expected file size: 14409734 08:07:07 INFO - Obtained file size: 14409734 08:07: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:07:07 INFO - trying https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:07:07 INFO - Downloading https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/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:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/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:07:08 INFO - Downloaded 55766955 bytes. 08:07:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:07:08 INFO - mkdir: /builds/slave/test/properties 08:07:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:07:08 INFO - Writing to file /builds/slave/test/properties/build_url 08:07:08 INFO - Contents: 08:07:08 INFO - build_url:https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:07:09 INFO - Running post-action listener: _resource_record_post_action 08:07:09 INFO - Running post-action listener: set_extra_try_arguments 08:07:09 INFO - [mozharness: 2016-12-09 16:07:09.291186Z] Finished download-and-extract step (success) 08:07:09 INFO - [mozharness: 2016-12-09 16:07:09.291412Z] Running populate-webroot step. 08:07:09 INFO - Running pre-action listener: _resource_record_pre_action 08:07:09 INFO - Running main action method: populate_webroot 08:07:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:07:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:07:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:07:09 INFO - '--tppagecycles', 08:07:09 INFO - '7'], 08:07:09 INFO - 'tests': ['tsvgm']}}, 08:07:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:07:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:07:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:07:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:07:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:07:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['damp', 'tps']}, 08:07:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'tests': ['damp', 'tps']}, 08:07:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['dromaeo_dom']}, 08:07:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:07:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['basic_compositor_video']}, 08:07:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:07:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['a11yr', 08:07:09 INFO - 'ts_paint', 08:07:09 INFO - 'tpaint', 08:07:09 INFO - 'sessionrestore', 08:07:09 INFO - 'sessionrestore_no_auto_restore', 08:07:09 INFO - 'tabpaint']}, 08:07:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['tsvgx', 08:07:09 INFO - 'tsvgr_opacity', 08:07:09 INFO - 'tart', 08:07:09 INFO - 'tscrollx', 08:07:09 INFO - 'cart', 08:07:09 INFO - 'tsvg_static']}, 08:07:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:07:09 INFO - 'tsvgr_opacity', 08:07:09 INFO - 'tart', 08:07:09 INFO - 'tscrollx', 08:07:09 INFO - 'cart', 08:07:09 INFO - 'tsvg_static']}, 08:07:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'talos_options': ['--disable-e10s'], 08:07:09 INFO - 'tests': ['tp5o']}, 08:07:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:07:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'talos_options': ['--disable-e10s', 08:07:09 INFO - '--xperf_path', 08:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:09 INFO - 'tests': ['tp5n']}, 08:07:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:07:09 INFO - 'talos_options': ['--xperf_path', 08:07:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:09 INFO - 'tests': ['tp5n']}}, 08:07:09 INFO - 'talos.zip': {'path': '', 08:07:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:07:09 INFO - Running post-action listener: _resource_record_post_action 08:07:09 INFO - [mozharness: 2016-12-09 16:07:09.313214Z] Finished populate-webroot step (success) 08:07:09 INFO - [mozharness: 2016-12-09 16:07:09.313302Z] Running create-virtualenv step. 08:07:09 INFO - Running pre-action listener: _resource_record_pre_action 08:07:09 INFO - Running main action method: create_virtualenv 08:07:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:07:09 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:07:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:07:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:07:09 INFO - Using real prefix '/usr' 08:07:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:07:09 INFO - Installing distribute.............................................................................................................................................................................................done. 08:07:11 INFO - Installing pip.................done. 08:07:11 INFO - Return code: 0 08:07:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:07:12 INFO - Reading from file tmpfile_stdout 08:07:12 INFO - Output received: 08:07:12 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:07:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:07:12 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:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:12 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:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:12 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:12 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:07:12 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:07:12 INFO - Using env: {'DISPLAY': ':0', 08:07:12 INFO - 'HOME': '/home/cltbld', 08:07:12 INFO - 'LANG': 'en_US.UTF-8', 08:07:12 INFO - 'LANGUAGE': 'en_US:en', 08:07:12 INFO - 'LOGNAME': 'cltbld', 08:07:12 INFO - 'MAIL': '/var/mail/cltbld', 08:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:12 INFO - 'MOZ_NO_REMOTE': '1', 08:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:12 INFO - 'NO_EM_RESTART': '1', 08:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:12 INFO - 'PWD': '/builds/slave/test', 08:07:12 INFO - 'SHELL': '/bin/bash', 08:07:12 INFO - 'SHLVL': '1', 08:07:12 INFO - 'TERM': 'linux', 08:07:12 INFO - 'TMOUT': '86400', 08:07:12 INFO - 'USER': 'cltbld', 08:07:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:12 INFO - '_': '/tools/buildbot/bin/python'} 08:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:12 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:07:12 INFO - Cleaning up... 08:07:12 INFO - Return code: 0 08:07:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:07:12 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:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:12 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:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:12 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:12 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:07:12 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:07:12 INFO - Using env: {'DISPLAY': ':0', 08:07:12 INFO - 'HOME': '/home/cltbld', 08:07:12 INFO - 'LANG': 'en_US.UTF-8', 08:07:12 INFO - 'LANGUAGE': 'en_US:en', 08:07:12 INFO - 'LOGNAME': 'cltbld', 08:07:12 INFO - 'MAIL': '/var/mail/cltbld', 08:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:12 INFO - 'MOZ_NO_REMOTE': '1', 08:07:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:12 INFO - 'NO_EM_RESTART': '1', 08:07:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:12 INFO - 'PWD': '/builds/slave/test', 08:07:12 INFO - 'SHELL': '/bin/bash', 08:07:12 INFO - 'SHLVL': '1', 08:07:12 INFO - 'TERM': 'linux', 08:07:12 INFO - 'TMOUT': '86400', 08:07:12 INFO - 'USER': 'cltbld', 08:07:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:12 INFO - '_': '/tools/buildbot/bin/python'} 08:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:12 INFO - Downloading/unpacking psutil>=3.1.1 08:07:12 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:07:12 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:07:12 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:07:12 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:07:12 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:07:12 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:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:07:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:14 INFO - Installing collected packages: psutil 08:07:14 INFO - Running setup.py install for psutil 08:07:14 INFO - building 'psutil._psutil_linux' extension 08:07:14 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:07:14 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:07:14 INFO - building 'psutil._psutil_posix' extension 08:07:14 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:07:15 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:07:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:07:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:07:15 INFO - Successfully installed psutil 08:07:15 INFO - Cleaning up... 08:07:15 INFO - Return code: 0 08:07:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:07:15 INFO - Using env: {'DISPLAY': ':0', 08:07:15 INFO - 'HOME': '/home/cltbld', 08:07:15 INFO - 'LANG': 'en_US.UTF-8', 08:07:15 INFO - 'LANGUAGE': 'en_US:en', 08:07:15 INFO - 'LOGNAME': 'cltbld', 08:07:15 INFO - 'MAIL': '/var/mail/cltbld', 08:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:15 INFO - 'MOZ_NO_REMOTE': '1', 08:07:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:15 INFO - 'NO_EM_RESTART': '1', 08:07:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:15 INFO - 'PWD': '/builds/slave/test', 08:07:15 INFO - 'SHELL': '/bin/bash', 08:07:15 INFO - 'SHLVL': '1', 08:07:15 INFO - 'TERM': 'linux', 08:07:15 INFO - 'TMOUT': '86400', 08:07:15 INFO - 'USER': 'cltbld', 08:07:15 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:15 INFO - '_': '/tools/buildbot/bin/python'} 08:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:07: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:07: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:07: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:07: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:07: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:07: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:07:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:07:17 INFO - Installing collected packages: mozsystemmonitor 08:07:17 INFO - Running setup.py install for mozsystemmonitor 08:07:17 INFO - Successfully installed mozsystemmonitor 08:07:17 INFO - Cleaning up... 08:07:17 INFO - Return code: 0 08:07:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:07:17 INFO - Using env: {'DISPLAY': ':0', 08:07:17 INFO - 'HOME': '/home/cltbld', 08:07:17 INFO - 'LANG': 'en_US.UTF-8', 08:07:17 INFO - 'LANGUAGE': 'en_US:en', 08:07:17 INFO - 'LOGNAME': 'cltbld', 08:07:17 INFO - 'MAIL': '/var/mail/cltbld', 08:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:17 INFO - 'MOZ_NO_REMOTE': '1', 08:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:17 INFO - 'NO_EM_RESTART': '1', 08:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:17 INFO - 'PWD': '/builds/slave/test', 08:07:17 INFO - 'SHELL': '/bin/bash', 08:07:17 INFO - 'SHLVL': '1', 08:07:17 INFO - 'TERM': 'linux', 08:07:17 INFO - 'TMOUT': '86400', 08:07:17 INFO - 'USER': 'cltbld', 08:07:17 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:17 INFO - '_': '/tools/buildbot/bin/python'} 08:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:17 INFO - Downloading/unpacking jsonschema==2.5.1 08:07:17 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:07:17 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:07:17 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:07:17 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:07:17 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:07:17 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:07:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:07:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:07:19 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:07:19 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:07:19 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:07:19 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:07:19 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:07:19 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:07:20 INFO - Downloading functools32-3.2.3-2.tar.gz 08:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:07:20 INFO - warning: no files found matching '*.txt' 08:07:20 INFO - no previously-included directories found matching 'build' 08:07:20 INFO - no previously-included directories found matching 'dist' 08:07:20 INFO - no previously-included directories found matching '.git*' 08:07:20 INFO - Installing collected packages: jsonschema, functools32 08:07:20 INFO - Running setup.py install for functools32 08:07:20 INFO - warning: no files found matching '*.txt' 08:07:20 INFO - no previously-included directories found matching 'build' 08:07:20 INFO - no previously-included directories found matching 'dist' 08:07:20 INFO - no previously-included directories found matching '.git*' 08:07:20 INFO - Successfully installed jsonschema functools32 08:07:20 INFO - Cleaning up... 08:07:20 INFO - Return code: 0 08:07:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:07:20 INFO - Using env: {'DISPLAY': ':0', 08:07:20 INFO - 'HOME': '/home/cltbld', 08:07:20 INFO - 'LANG': 'en_US.UTF-8', 08:07:20 INFO - 'LANGUAGE': 'en_US:en', 08:07:20 INFO - 'LOGNAME': 'cltbld', 08:07:20 INFO - 'MAIL': '/var/mail/cltbld', 08:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:20 INFO - 'MOZ_NO_REMOTE': '1', 08:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:20 INFO - 'NO_EM_RESTART': '1', 08:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:20 INFO - 'PWD': '/builds/slave/test', 08:07:20 INFO - 'SHELL': '/bin/bash', 08:07:20 INFO - 'SHLVL': '1', 08:07:20 INFO - 'TERM': 'linux', 08:07:20 INFO - 'TMOUT': '86400', 08:07:20 INFO - 'USER': 'cltbld', 08:07:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:20 INFO - '_': '/tools/buildbot/bin/python'} 08:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:07:20 INFO - Cleaning up... 08:07:20 INFO - Return code: 0 08:07:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:07:20 INFO - Using env: {'DISPLAY': ':0', 08:07:20 INFO - 'HOME': '/home/cltbld', 08:07:20 INFO - 'LANG': 'en_US.UTF-8', 08:07:20 INFO - 'LANGUAGE': 'en_US:en', 08:07:20 INFO - 'LOGNAME': 'cltbld', 08:07:20 INFO - 'MAIL': '/var/mail/cltbld', 08:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:20 INFO - 'MOZ_NO_REMOTE': '1', 08:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:20 INFO - 'NO_EM_RESTART': '1', 08:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:20 INFO - 'PWD': '/builds/slave/test', 08:07:20 INFO - 'SHELL': '/bin/bash', 08:07:20 INFO - 'SHLVL': '1', 08:07:20 INFO - 'TERM': 'linux', 08:07:20 INFO - 'TMOUT': '86400', 08:07:20 INFO - 'USER': 'cltbld', 08:07:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:20 INFO - '_': '/tools/buildbot/bin/python'} 08:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:20 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:22 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:07:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:07:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:23 INFO - Downloading docopt-0.6.1.tar.gz 08:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:07:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:23 INFO - Running setup.py install for blobuploader 08:07:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:07:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:07:23 INFO - Running setup.py install for requests 08:07:23 INFO - Running setup.py install for docopt 08:07:24 INFO - Successfully installed blobuploader requests docopt 08:07:24 INFO - Cleaning up... 08:07:24 INFO - Return code: 0 08:07:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:24 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:24 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:07:24 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:07:24 INFO - Using env: {'DISPLAY': ':0', 08:07:24 INFO - 'HOME': '/home/cltbld', 08:07:24 INFO - 'LANG': 'en_US.UTF-8', 08:07:24 INFO - 'LANGUAGE': 'en_US:en', 08:07:24 INFO - 'LOGNAME': 'cltbld', 08:07:24 INFO - 'MAIL': '/var/mail/cltbld', 08:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:24 INFO - 'MOZ_NO_REMOTE': '1', 08:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:24 INFO - 'NO_EM_RESTART': '1', 08:07:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:24 INFO - 'PWD': '/builds/slave/test', 08:07:24 INFO - 'SHELL': '/bin/bash', 08:07:24 INFO - 'SHLVL': '1', 08:07:24 INFO - 'TERM': 'linux', 08:07:24 INFO - 'TMOUT': '86400', 08:07:24 INFO - 'USER': 'cltbld', 08:07:24 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:24 INFO - '_': '/tools/buildbot/bin/python'} 08:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:24 INFO - Running setup.py (path:/tmp/pip-asPsM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:24 INFO - Running setup.py (path:/tmp/pip-SpQZmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:24 INFO - Running setup.py (path:/tmp/pip-yW9ycv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:24 INFO - Running setup.py (path:/tmp/pip-W6snUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:24 INFO - Running setup.py (path:/tmp/pip-uCJO00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:24 INFO - Running setup.py (path:/tmp/pip-h9aaQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:24 INFO - Running setup.py (path:/tmp/pip-3HaFAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:24 INFO - Running setup.py (path:/tmp/pip-gidWV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:24 INFO - Running setup.py (path:/tmp/pip-YGNaX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:25 INFO - Running setup.py (path:/tmp/pip-S7nqBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:25 INFO - Running setup.py (path:/tmp/pip-S0dOtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:25 INFO - Running setup.py (path:/tmp/pip-CDZIZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:25 INFO - Running setup.py (path:/tmp/pip-AUQ4ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:25 INFO - Running setup.py (path:/tmp/pip-ndK9La-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:25 INFO - Running setup.py (path:/tmp/pip-dGtaAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:25 INFO - Running setup.py (path:/tmp/pip-3pix1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:25 INFO - Running setup.py (path:/tmp/pip-8p1ZqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:07:25 INFO - Running setup.py install for manifestparser 08:07:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:07:25 INFO - Running setup.py install for mozcrash 08:07:25 INFO - Running setup.py install for mozdebug 08:07:25 INFO - Running setup.py install for mozdevice 08:07:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozfile 08:07:26 INFO - Running setup.py install for mozhttpd 08:07:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozinfo 08:07:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozInstall 08:07:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozleak 08:07:26 INFO - Running setup.py install for mozlog 08:07:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for moznetwork 08:07:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozprocess 08:07:26 INFO - Running setup.py install for mozprofile 08:07:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:07:26 INFO - Running setup.py install for mozrunner 08:07:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:07:27 INFO - Running setup.py install for mozscreenshot 08:07:27 INFO - Running setup.py install for moztest 08:07:27 INFO - Running setup.py install for mozversion 08:07:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:07:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:07:27 INFO - Cleaning up... 08:07:27 INFO - Return code: 0 08:07:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:27 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:27 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:07:27 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:07:27 INFO - Using env: {'DISPLAY': ':0', 08:07:27 INFO - 'HOME': '/home/cltbld', 08:07:27 INFO - 'LANG': 'en_US.UTF-8', 08:07:27 INFO - 'LANGUAGE': 'en_US:en', 08:07:27 INFO - 'LOGNAME': 'cltbld', 08:07:27 INFO - 'MAIL': '/var/mail/cltbld', 08:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:27 INFO - 'MOZ_NO_REMOTE': '1', 08:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:27 INFO - 'NO_EM_RESTART': '1', 08:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:27 INFO - 'PWD': '/builds/slave/test', 08:07:27 INFO - 'SHELL': '/bin/bash', 08:07:27 INFO - 'SHLVL': '1', 08:07:27 INFO - 'TERM': 'linux', 08:07:27 INFO - 'TMOUT': '86400', 08:07:27 INFO - 'USER': 'cltbld', 08:07:27 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:27 INFO - '_': '/tools/buildbot/bin/python'} 08:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:07:27 INFO - Running setup.py (path:/tmp/pip-EUIGid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:07:27 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:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:07:27 INFO - Running setup.py (path:/tmp/pip-uaCib5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:07:27 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:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:07:27 INFO - Running setup.py (path:/tmp/pip-ak6rIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:07:27 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:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:07:27 INFO - Running setup.py (path:/tmp/pip-24iFcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:07:27 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:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:07:27 INFO - Running setup.py (path:/tmp/pip-cB2Zhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:07:27 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:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:07:27 INFO - Running setup.py (path:/tmp/pip-bO4WHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:07:28 INFO - Running setup.py (path:/tmp/pip-XkPioG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:07:28 INFO - Running setup.py (path:/tmp/pip-jsgHK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:07:28 INFO - Running setup.py (path:/tmp/pip-cjjlQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:07:28 INFO - Running setup.py (path:/tmp/pip-TxMpe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:07:28 INFO - Running setup.py (path:/tmp/pip-GpPRxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:07:28 INFO - Running setup.py (path:/tmp/pip-9V7Pv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:07:28 INFO - Running setup.py (path:/tmp/pip-sTt21r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:07:28 INFO - Running setup.py (path:/tmp/pip-M76C8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:28 INFO - Running setup.py (path:/tmp/pip-knOWAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:07:28 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:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:07:28 INFO - Running setup.py (path:/tmp/pip-ktcvKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:07:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:07:28 INFO - Running setup.py (path:/tmp/pip-A37LOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:07:28 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:07:28 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:07:28 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:07:28 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:07:28 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:07:28 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:07:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:07:30 INFO - Downloading blessings-1.6.tar.gz 08:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:07:31 INFO - Installing collected packages: blessings 08:07:31 INFO - Running setup.py install for blessings 08:07:31 INFO - Successfully installed blessings 08:07:31 INFO - Cleaning up... 08:07:31 INFO - Return code: 0 08:07:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:31 INFO - Reading from file tmpfile_stdout 08:07:31 INFO - Current package versions: 08:07:31 INFO - argparse == 1.2.1 08:07:31 INFO - blessings == 1.6 08:07:31 INFO - blobuploader == 1.2.4 08:07:31 INFO - docopt == 0.6.1 08:07:31 INFO - functools32 == 3.2.3-2 08:07:31 INFO - jsonschema == 2.5.1 08:07:31 INFO - manifestparser == 1.1 08:07:31 INFO - mozInstall == 1.12 08:07:31 INFO - mozcrash == 1.0 08:07:31 INFO - mozdebug == 0.1 08:07:31 INFO - mozdevice == 0.49 08:07:31 INFO - mozfile == 1.2 08:07:31 INFO - mozhttpd == 0.7 08:07:31 INFO - mozinfo == 0.9 08:07:31 INFO - mozleak == 0.1 08:07:31 INFO - mozlog == 3.4 08:07:31 INFO - moznetwork == 0.27 08:07:31 INFO - mozprocess == 0.23 08:07:31 INFO - mozprofile == 0.28 08:07:31 INFO - mozrunner == 6.13 08:07:31 INFO - mozscreenshot == 0.1 08:07:31 INFO - mozsystemmonitor == 0.3 08:07:31 INFO - moztest == 0.7 08:07:31 INFO - mozversion == 1.4 08:07:31 INFO - psutil == 3.1.1 08:07:31 INFO - requests == 1.2.3 08:07:31 INFO - wsgiref == 0.1.2 08:07:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:07:31 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:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:31 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:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:31 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:31 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:07:31 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:07:31 INFO - Using env: {'DISPLAY': ':0', 08:07:31 INFO - 'HOME': '/home/cltbld', 08:07:31 INFO - 'LANG': 'en_US.UTF-8', 08:07:31 INFO - 'LANGUAGE': 'en_US:en', 08:07:31 INFO - 'LOGNAME': 'cltbld', 08:07:31 INFO - 'MAIL': '/var/mail/cltbld', 08:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:31 INFO - 'MOZ_NO_REMOTE': '1', 08:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:31 INFO - 'NO_EM_RESTART': '1', 08:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:31 INFO - 'PWD': '/builds/slave/test', 08:07:31 INFO - 'SHELL': '/bin/bash', 08:07:31 INFO - 'SHLVL': '1', 08:07:31 INFO - 'TERM': 'linux', 08:07:31 INFO - 'TMOUT': '86400', 08:07:31 INFO - 'USER': 'cltbld', 08:07:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:31 INFO - '_': '/tools/buildbot/bin/python'} 08:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 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:07:31 INFO - Cleaning up... 08:07:31 INFO - Return code: 0 08:07:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:07:31 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:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:31 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:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:07:31 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 0x7f8558fc5e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1111a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12af120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', '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:07:31 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:07:31 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:07:31 INFO - Using env: {'DISPLAY': ':0', 08:07:31 INFO - 'HOME': '/home/cltbld', 08:07:31 INFO - 'LANG': 'en_US.UTF-8', 08:07:31 INFO - 'LANGUAGE': 'en_US:en', 08:07:31 INFO - 'LOGNAME': 'cltbld', 08:07:31 INFO - 'MAIL': '/var/mail/cltbld', 08:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:31 INFO - 'MOZ_NO_REMOTE': '1', 08:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:31 INFO - 'NO_EM_RESTART': '1', 08:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:31 INFO - 'PWD': '/builds/slave/test', 08:07:31 INFO - 'SHELL': '/bin/bash', 08:07:31 INFO - 'SHLVL': '1', 08:07:31 INFO - 'TERM': 'linux', 08:07:31 INFO - 'TMOUT': '86400', 08:07:31 INFO - 'USER': 'cltbld', 08:07:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:31 INFO - '_': '/tools/buildbot/bin/python'} 08:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:07:31 INFO - Cleaning up... 08:07:31 INFO - Return code: 0 08:07:31 INFO - Running post-action listener: _resource_record_post_action 08:07:31 INFO - Running post-action listener: _start_resource_monitoring 08:07:31 INFO - Starting resource monitoring. 08:07:31 INFO - [mozharness: 2016-12-09 16:07:31.977081Z] Finished create-virtualenv step (success) 08:07:31 INFO - [mozharness: 2016-12-09 16:07:31.977599Z] Running install step. 08:07:31 INFO - Running pre-action listener: _resource_record_pre_action 08:07:31 INFO - Running main action method: install 08:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:07:32 INFO - Reading from file tmpfile_stdout 08:07:32 INFO - Detecting whether we're running mozinstall >=1.0... 08:07:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:07:32 INFO - Reading from file tmpfile_stdout 08:07:32 INFO - Output received: 08:07:32 INFO - Usage: mozinstall [options] installer 08:07:32 INFO - Options: 08:07:32 INFO - -h, --help show this help message and exit 08:07:32 INFO - -d DEST, --destination=DEST 08:07:32 INFO - Directory to install application into. [default: 08:07:32 INFO - "/builds/slave/test"] 08:07:32 INFO - --app=APP Application being installed. [default: firefox] 08:07:32 INFO - mkdir: /builds/slave/test/build/application 08:07:32 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:07:32 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:07:48 INFO - Reading from file tmpfile_stdout 08:07:48 INFO - Output received: 08:07:48 INFO - /builds/slave/test/build/application/firefox/firefox 08:07:48 INFO - Running post-action listener: _resource_record_post_action 08:07:48 INFO - [mozharness: 2016-12-09 16:07:48.328778Z] Finished install step (success) 08:07:48 INFO - [mozharness: 2016-12-09 16:07:48.329022Z] Running run-tests step. 08:07:48 INFO - Running pre-action listener: _resource_record_pre_action 08:07:48 INFO - Running main action method: run_tests 08:07:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:07:48 INFO - Python 2.7.3 08:07:48 INFO - Return code: 0 08:07:48 INFO - grabbing minidump binary from tooltool 08:07:48 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:07:48 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 0x133ff20>, '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 0x12acc20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12acf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:07:48 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:07:48 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:07:48 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:07:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:07:48 INFO - Return code: 0 08:07:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:07:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:07:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:07:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:07:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:07:48 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/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:07:48 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/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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:07:48 INFO - Using env: {'DISPLAY': ':0', 08:07:48 INFO - 'HOME': '/home/cltbld', 08:07:48 INFO - 'LANG': 'en_US.UTF-8', 08:07:48 INFO - 'LANGUAGE': 'en_US:en', 08:07:48 INFO - 'LOGNAME': 'cltbld', 08:07:48 INFO - 'MAIL': '/var/mail/cltbld', 08:07:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:07:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:48 INFO - 'MOZ_NO_REMOTE': '1', 08:07:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:07:48 INFO - 'NO_EM_RESTART': '1', 08:07:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:48 INFO - 'PWD': '/builds/slave/test', 08:07:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:07:48 INFO - 'SHELL': '/bin/bash', 08:07:48 INFO - 'SHLVL': '1', 08:07:48 INFO - 'TERM': 'linux', 08:07:48 INFO - 'TMOUT': '86400', 08:07:48 INFO - 'USER': 'cltbld', 08:07:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116', 08:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:48 INFO - '_': '/tools/buildbot/bin/python'} 08:07:48 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/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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:07:48 INFO - mozversion application_buildid: 20161209064651 08:07:48 INFO - mozversion application_changeset: 8404d26166a35406f46ff237ed132735c98882b2 08:07:48 INFO - mozversion application_display_name: Nightly 08:07:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:07:48 INFO - mozversion application_name: Firefox 08:07:48 INFO - mozversion application_remotingname: firefox 08:07:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:07:48 INFO - mozversion application_vendor: Mozilla 08:07:48 INFO - mozversion application_version: 53.0a1 08:07:48 INFO - mozversion platform_buildid: 20161209064651 08:07:48 INFO - mozversion platform_changeset: 8404d26166a35406f46ff237ed132735c98882b2 08:07:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:07:48 INFO - mozversion platform_version: 53.0a1 08:07:48 INFO - using testdate: 1481299668 08:07:48 INFO - actual date: 1481299668 08:07:48 INFO - starting webserver on 'localhost:45429' 08:07:48 INFO - SUITE-START | Running 2 tests 08:07:48 INFO - TEST-START | dromaeo_css 08:07:48 INFO - operating with platform_type : linux_ 08:07:48 INFO - Initialising browser for dromaeo_css test... 08:07:48 INFO - TEST-INFO | started process 23797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_6O8E9/profile http://localhost:45429/getInfo.html) 08:07:52 INFO - PROCESS | 23797 | __metrics Screen width/height:1600/1200 08:07:52 INFO - PROCESS | 23797 | colorDepth:24 08:07:52 INFO - PROCESS | 23797 | Browser inner width/height: 1024/697 08:07:52 INFO - PROCESS | 23797 | __metrics 08:07:53 INFO - PROCESS | 23797 | 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:07:53 INFO - PROCESS | 23797 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:07:54 INFO - TEST-INFO | 23797: exit 0 08:07:54 INFO - Browser initialized. 08:07:54 INFO - Running cycle 1/1 for dromaeo_css test... 08:07:54 INFO - TEST-INFO | started process 23876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_6O8E9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:07 INFO - PROCESS | 23876 | RSS: Main: 173899776 08:08:07 INFO - PROCESS | 23876 | 08:08:58 INFO - PROCESS | 23876 | 1481299738395 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:11:12 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45429/tests/dromaeo/cssquery-ext.html) 08:11:14 INFO - PROCESS | 23876 | RSS: Main: 322424832 08:11:14 INFO - PROCESS | 23876 | 08:14:17 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-ext.html (next: http://localhost:45429/tests/dromaeo/cssquery-jquery.html) 08:14:17 INFO - PROCESS | 23876 | RSS: Main: 272977920 08:14:17 INFO - PROCESS | 23876 | 08:17:20 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45429/tests/dromaeo/cssquery-mootools.html) 08:17:20 INFO - PROCESS | 23876 | RSS: Main: 218345472 08:17:20 INFO - PROCESS | 23876 | 08:20:23 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45429/tests/dromaeo/cssquery-prototype.html) 08:20:23 INFO - PROCESS | 23876 | RSS: Main: 219672576 08:20:23 INFO - PROCESS | 23876 | 08:23:27 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45429/tests/dromaeo/cssquery-yui.html) 08:23:27 INFO - PROCESS | 23876 | RSS: Main: 224284672 08:23:27 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | Cycle 1(1): loaded http://localhost:45429/tests/dromaeo/cssquery-yui.html (next: http://localhost:45429/tests/dromaeo/cssquery-dojo.html) 08:26:30 INFO - PROCESS | 23876 | RSS: Main: 311783424 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | __start_tp_report 08:26:30 INFO - PROCESS | 23876 | _x_x_mozilla_page_load 08:26:30 INFO - PROCESS | 23876 | _x_x_mozilla_page_load_details 08:26:30 INFO - PROCESS | 23876 | |i|pagename|runs| 08:26:30 INFO - PROCESS | 23876 | |0;dojo.html;2478.987605061975;2566.704828944672;2586.7930565554757;2599.4930988457245;2606.7262937391574;4422.911541769163;4512.16524942885;4518.774061296938;4543.341215523747;4549.5450454954525;4539.364488971547;4560.429946256718;4588.518205588415;4598.149342371664;4670.626349892009;4131.73143745657;4135.31767258402;4184.623383895449;4234.491860976672;4317.222899878021;4239.745615263078;4323.178596066753;4323.437953066097;4351.608355248027;4400.031992961543;4125.876223713293;4252.489701235841;4262.126264115849;4317.352397140423;4355.499117601485;4152.8131234094535;4210.831566737327;4212.641925436342;4221.97889010553;4222.071144348239;4194.706370554062;4197.202531519001;4198.85304014358;4200.180964711883;4207.137536804948;4599.379083823675;4630.836617171829;4640.86077417678;4659.44086709597;4714.151452738506;4917.336159618441;4933.210686290212;4939.728314942677;4973.378327709036;4983.551480366748;4757.215059515176;4939.654224204307;4956;4957.132501812204;4994.35073440454;4476.529964353728;4478.9328160076975;4509.909801803946;4535.25168347219;4605.032943081923;4971.1797553403285;4972.105021096165;4977.626677999165;4978.4025916890205;4993.675411098339;4803.207470767354;4804.783784729696;4812.663113582085;4813.326134341195;4857.101436234308;4751.548602882721;4804.25534042224;4809.062232864628;4861.489543597942;4865.343178670905;4778.808847646124;4811.9518804811505;4835.927461088017;4877.073356062337;4883.4628190898975;4842.878928026757;4844.927326090041;4845.63657725672;4871.464138944713;4902.50974902514;4052;4059.9594004060173;4064.573219811877;4068.633822955889;4098.508179018478;3513.1217195701074;3543.185067434453;3548.27260411615;3605.3330133925306;3613.313470440608;4246;4256.808443620044;4284.935725964113;4287.056847493546;4287.914241715211;4864.927026094611;4877.048975449753;4879.634027447955;4880.316755654211;4885.5358740919555;4250.426192463978;4261.509926358446;4263.360495925636;4283;4295;4343.2182207203;4355.194289056535;4355.237833379209;4355.869323920224;4392.363107349416;4516.751578663205;4640.280756482751;4640.535946405333;4648.37246971668;4695.718256904596;4712.151812673752;4781.641376896816;4825.324454576432;4860.902781944412;4880.46314905353;4630.745309008037;4631.861044168681;4633.675642705113;4661.976690116528;4675.205215113507;4817.807287708452;4824.179889418737;4827.227643577011;4828.903421931612;4867.075255701406;4786.664933454764;4822.68652537584;4828.830990915301;4872.4640289567415;4894.2658601210105;4729.952700472952;4740.3837501124635;4749.620030397491;4786.497417771084;4820.493848146035;4785.521447855326;4792.041591681747;4792.400949881265;4794.137055330074;4855.320255164209;4568.70303430276;4742.976285118552;4748.691335063209;4758.286257061469;4794.137055329935;4701.717896926195;4756.191447453872;4783.043391321819;4804;4829.782659780248;4720.055988802322;4727.456342520517;4730.1958667026;4766.547178018082;4770.594499467584 08:26:30 INFO - PROCESS | 23876 | |1;ext.html;541.8320320700582;549.1488193300388;550.0758725341425;559.5104283751717;572.6106247751528;26672.333191670194;39181.80409097954;41021.384679229835;41097.17805643889;41192;38170.809145954234;38212.617873821255;38563.807180964126;38572;38678.61321386785;7340.816479588027;7495.812604684872;7506.09926808781;7510.549367037968;7513.774586762379;7303.780886573385;7507.324340809326;9523.238140948708;9793.167580755644;9795.608175672964;9747;9844.3601165924;9848.704538863845;9848.901510984906;9852;9775.560099795526;9856.260780441424;9856.556454959544;9863.901360986405;9865.90134098657;9722.173615242713;9801.215902727765;9801.411915285105;9805.215582753433;9809.852852207223;36072;36319.63680363189;36453.81773091118;36486;36503.817480912694;3091.629004519472;3133.0444214514564;3213.5179723041497;3298.340331933623;3566.4828599852876;26863.194104176906;26876.73123268762;26953.55840862254;27137.4544590527;27677.58483622727;3279.5199053334395;3375.9662403375896;3409.8465569049204;3410.6248312685584;3483.4078206704667;221204;221310;222067;222125;222449;49480.75259623679;49916.75041624768;50103.24845127326;50121;50126.49873501218;46589.5341046592;51182.23226651603;51603.25951107289;51762.741186293824;51769.74115129476;12537.185082969578;13168.341582920815;13180.670483237804;13189.604311870658;13190.142640728322;8929.24101451371;9189.862152067724;9995.800083998425;10057.849132263022;10062.597496100074;14573.720912794537;15014.624634384229;15188.240587970557;15551.377944882079;15673.137977411352;5850.95534430693;5941.732622032019;6018.398160183947;6064.120702498201;6073.787417440369;772.6175543106199;894.3605322194662;930.5207817973749;953.7377221264207;1002.3284399452384;25864.353391165372;27091.593626095626;27502.449951000868;27557.31106722308;27598.172054838386;840.7813968368146;906.1301150895215;929.9116583924516;982.1946004276425;984.4313274967307;13462.932685336511;14330.57008289753;14351.282435878164;14487.78268325976;14653;319762;320415;320538;322451;323203;46098.5390146101;46143;46181.76909115433;46214;46215.30677039847;1071.908887744535;1109.545086514525;1142.6000899685043;1180.8523934508187;1199.6880810989205;24216.362001798585;27647.58528622032;28099.297517562223;28243.293917652223;28416.715832841408;39264.2147157061;39765.80117099396;39807.2038559233;39828.60171398365;39889.80055099706;200820;201302;201809;202693;203114;22050.779492204874;22705;22787.88606056959;24056.27831165068;34127.31745365128;34794.304113917075;35272.29455410826;35447.82276088603;35805.82097089498;36159.45760813537;30215.848920755256;30271;30527;30645;31062.844685776425;29762.553561696157;30005.849970750878;30130.93232413596;30394.240143996576;30451.847740761154;30733;30949.845250774502;30970.53544196794;31267.84366078155;31294.530582040832;25734.70454941592;27375;28088.71911280861;28435;28524.85737571299;21804.236851710113;22077.448063798533;22361.105555777587;22417.77582224222;22441 08:26:30 INFO - PROCESS | 23876 | |2;jquery.html;335.5771727623194;350.2906614106434;353.44332676035236;355.2646022732942;369.88533554598075;12357.752844943096;15136;15408;16802.579935501617;16986.83013169868;16183.514494565152;16290.02259864409;16528.58678533037;16629.91685041576;16694.83305166951;4705.764711764416;4731.881702957437;4907.337509436234;4944.752762361885;4953.603711703073;4824.6381521385865;4826.710397376151;4873.098476781789;4873.390826146731;4880.414350277972;3604.3151801157696;3791.9810400948095;5298.496642818926;5516.117421212607;5539.889202215953;5397.8380648580605;5613.66318020918;5614.242077319591;5617.185508101344;5619.4099619540175;4991.101601711691;5024.698518088926;5033.219850923113;5035.647504674675;5036.219385995177;18837.058147092728;19457.124429400712;19484.70772938407;19776.011199440116;19840.404787856387;1913.2596220024889;2075.937721868346;2163.0806907064434;2307.8384513084093;2405.8990985291784;11748.941255293754;12800.743985120338;13002.219866808022;13125.934370328087;13302.93348533251;1648.5136884619067;1698.1339516846374;1730.5327561558436;1747.1177055586975;1769.2392271323326;60131.39868601302;60984;62230;62384.68807655932;62485;8933.374663773473;9014.278857691368;9103.726888193365;9313.860292095624;9562.139407453362;12622.747545049047;12623.053271004712;12626.053046021585;12626.747465050832;12630;6490.318516555788;6628.740539297518;6683.899741503881;6756.763513277012;6855.314468553204;6335.873282534323;6360.268569114611;6526.673666316665;6527.118838956775;6545.476361891037;5757.769689212385;5889.852753681193;5938.164404792694;5949.10763385487;5981.611195272293;1649.983500164983;1673.7238355671177;1700;1709.7606335113094;1756.4730580825956;750.9962450187714;802.0896282719156;822.2558584480935;828.6105530400702;840.2018082821344;15936.760948585781;16085.276162572713;16145.434909778101;16464.341426343053;17088.57278568046;857.1557016338901;881.7354793561906;894.5437826708295;906.7551761024619;907.7095329494631;9052.81894362109;9656.130948214695;9722.076402741728;9754.0733630305;9802.256941858624;326719;331643;332069;333397;334509;36479.635203648155;44155.116897661865;60661;61342.69328653328;62170.68914655398;378.3360202843991;388.7084686485136;412.76693090380894;413.2066432449704;883.3242569434387;8319.667213311643;8716.302695784449;8997.55012249398;9143.63425462974;9579.185769209416;31804;32971.50542741813;33032.33935321328;33679.326413471106;34029.31941361109;40306.79846600748;40321.59678403178;41488.79255603703;42019.1596168081;42037.57962420337;7113.608751518716;7203.135623725187;7258.818529536804;7558.546487210785;7588;9396.342256042011;10534.42060686639;10598.470076496298;10757.354558726502;10839.620613278497;9416;9514.476703781082;9687.854682179914;9723.367981081206;10016.799664006534;9465.47939863314;9655.565499552396;9683;9891.455969921722;9923.702288931343;9184.12750788674;9440;9441.15029647335;9538.189253913495;9712.854307185538;7290.854182916418;7452.180260171485;7584.5070070445245;7600;7611.200823913409;8546.35902307317;9777.804443911224;9818.803623927624;10143.797124057624;10302.33034852732 08:26:30 INFO - PROCESS | 23876 | |3;mootools.html;1332.573576455534;1354.3093022558496;1428.692831041326;1612.9435469758562;1628.8126865410475;6813.523053386265;7210.423166146708;7221.025161603196;7233.855322893545;7240.13118425789;5970.373110823355;6237.750489980395;6801.761938332159;7086.645667716606;7100.609466479342;2061.0622166914054;2088.0708084902226;2092.0585736418598;2093.0476633131966;2096.0777258006506;2105.2528024948283;2133.0827744070043;2151.612709712251;2152.9784702153015;2153.5800518898823;1788.7495750594926;1878.9812101878942;1984.8312893404077;2070.554830711397;2080.947976300597;2020.7676117246485;2044.6115238104862;2045.0694933805082;2047.2322878920404;2047.396018174642;2066.5970135823522;2103.621348157331;2132.3816093332916;2171.0989939175224;2175.641019231825;7263.3462988330775;7264.673089710976;7267.382272506843;7282.490225684204;7297.817554561126;4230.957690423118;4344.044310251739;4362.738235705836;4367.235733746614;4370.082282720632;2538.885750141248;2606.8044896632637;2682.3562345037244;2697.5144473994674;2708.214617760847;803.863343231652;817.2767101115496;840.2185967050641;907.5552979040289;911.334725650279;9651.71044868655;9702.805943881154;9719.46542940138;9719.757006074835;9730.12428881397;3702.5186725725484;3822.10180607557;3849.942250866239;3906.9609303907164;4039.2331253091725;8371.958140209383;8382.958085209535;8385.706500272583;8388.580570971428;8412.116727743622;4319.805608747614;4393.077453734688;4397.472303323558;4425.3140763181755;4438.4451943507065;3686.1521849974656;4014.959850401517;4015.8996025099605;4020.3567429211184;4022.899427514277;3356.177736454584;3391.8304084795664;3458.9308213836084;3475.252820643523;3574.8033858137533;1120.994395028036;1132.2413982631492;1148.1962626161721;1159.049579344929;1160.843286156375;616.5067945643557;728.5919884864387;854;866.3935245328296;871.947683139001;4695.906081878412;4762.476127625959;4815.975920120377;4861.878453038702;5386.811461598903;812.959352032396;816.848882956655;842.3429724814654;861.7457849934284;886.0519244408421;3308.6360500344945;3408.693217610427;3459.532963049956;3473.1838018065732;3547.166415892263;8554.401191916506;8656.956715216384;8669.56652167389;8718.041015488292;8805.339599530062;8254.298384637374;8576.828463430822;8596.097409771884;8772.298216142693;8786;6679.064930909676;6807.081044059088;6816;6819.386255237053;6866.793996180123;4990.251462280687;5095.592352611856;5178.275041494118;5217.921731174111;5336.359636843604;7750.72113101332;7804.804879877821;7875;7910.644021019183;7971.123176450702;7828.686852525835;7968.282854543119;8040.155783642634;8107.635156417858;8153.6330865112395;5967.850803729942;6110.633361998294;6121.449069583581;6179.073139029181;6208.81373558794;6339.9683001586545;6344.714487847966;6347.270063942521;6377.87244255103;6466.321036291121;6089.391060893875;6116.449519543263;6178.969105154445;6181.412765787243;6265.122882796321;5919.556033297434;6079.088136779518;6137.41694539018;6165.691715414301;6274.309825919237;5791.218185545066;5842.2405087338375;5844.357120716632;5900.881982360414;5909.852253693692;4426;4432.246518091868;4479.0817882333395;4540.750258735801;4647.326137710081;4309.504406993111;4331.675124365748;4345;4408.338749187647;4415.116976604628 08:26:30 INFO - PROCESS | 23876 | |4;prototype.html;322.51622566150775;329.08186160611893;338.25414960013165;342.5786282872066;344.189433883205;4637;4731.692439991402;4738.052389522097;4740.478547359788;4756.881077973044;4423.800928958197;4682.578567928886;4695.779097434666;4698.08387364464;4730.763461826912;3707.555093388797;3716.2753263113705;3776.527934008249;3791.6587507124354;3821.847126114966;3785.1483416231267;3795.1650636859986;3802.429635554661;3817.751846129993;3818.7970789201313;3597.244578638489;3775.9056023599355;3778.1876896467256;3782.7352085354037;3787.280416720828;3687.760295580779;3744.0905138379485;3790.90522736931;3796.4115562087923;3809.695224382043;3354.9832250838836;3412.4540073588105;3422.19578399076;3426.126337783849;3426.3832510148163;4545.568171023748;4572.0855828834365;4583.9541604583865;4587.587117159472;4600.9769951150365;3976.363781794899;4101.487314085739;4126.649234815044;4278.657707383433;4320.26555485565;4849.272609108627;4852.369192005017;4861.927071093936;4869.902601947942;4872.634552408548;3432.2277487565348;3436.570428696413;3439.9680095971107;3466.757326987112;3471.5313432686517;4880.951190488121;5081.390233172006;5092;5116.641835071547;5127.8205262816;3474.808885511321;3500.177458297298;3507.5615548056494;3515.6132825389186;3534.8055856928117;3552.91117722059;3554.751167418256;3555.1289933966345;3571.1072231942017;3594.658507441789;4078.6125318094732;4096.3409819023345;4099.364598487239;4100.5489396166395;4109;4767.594754445841;4880.341153944243;4882.8779280517565;4909.337239472697;4917.852464426073;1202.753435545711;1210.4855436439477;1211.2248161176854;1215.3254943506438;1217.9634610961684;781.7185813107277;803.8392321535716;827.594478705442;837.3761547647042;927;4211.810468528863;4212.894677633022;4219.599138081878;4267.295896177158;4273.957260427418;4855.6115510759055;4889.3888263967;4896.632752543574;4915.705057696549;4922.680025798311;4128.690348223895;4254.468191476066;4270.572942705705;4291.463567054118;4303.849365272263;2574.8541898855206;2575.858327791952;2586.1594981631047;2589.6374507568958;2601.4375839797785;4624.5144259852905;4630.166570017429;4636.930446043312;4651.39516874425;4668.5798278155125;4769.809207631725;5000.2749601307605;5005.4961526930565;5027.340977477424;5055.3428054353435;4665.76357265325;4670.743109129031;4672.532746725368;4681.414823147106;4690.437147542249;4922.261660750916;4929.457759646364;4932.383452068491;4935.360760884757;4935.876603084952;4610.193216187221;4790.95209047919;4797.880052998703;4806.855794326176;4815.518448155157;4600.5629465200755;4621.953780462152;4631.421072365954;4631.698939568917;4636.6058884995155;3728.738988270795;3729.489556729529;3732.77899649708;3744.3447396704923;3780.2250538640064;4887.3890763654545;4905.313256144214;4908.509149085063;4910.2880082388565;4946.777395017161;4573.245414506569;4717.2216584263215;4735.621150308036;4742.359781429601;4753.764021354542;4652.371929789435;4672.205725026822;4673.719576825401;4680.368150299666;4703.600193983413;4640.791164397678;4645.2103142465185;4650.116477869194;4656.837010704609;4668.346431499661;4593.115797851121;4642.930356044594;4764.4282686077895;4768.856934291976;4770.403699537558;4639.504693429226;4649.558291962258;4650.837290677331;4660.533946605312;4674.6260299176665 08:26:30 INFO - PROCESS | 23876 | |5;yui.html;630.7540059376847;644.7839973608841;659.1299484680223;728.9357537994528;746.652806445003;12402.441890114944;13518.526851560197;13524.594262172126;13551.390187441564;13558.661033474167;13139.605811825637;13190.868091319084;13207.603771886836;13325.93337033316;13332;622.4771192198552;636.360457739973;762.5653377574785;767.2327672327673;781.0315209140681;766.1342682768492;787.1420152034282;787.7361084036826;787.8306164174701;788.7515432638701;823.7034667519688;826.9503829770202;826.9710560130396;827.4332082523491;827.4993628854546;646.7025168422531;661.3551787007677;782.6282515805004;810.2707563193114;810.6595230003413;597.5249676507187;599.3706608061561;601.5698775375628;602.0367412140583;602.0547740048127;12186.634400967896;12200.572979945659;12213.389330533439;12292.508299668003;12304.07719421047;12593.811092833615;12682.682932926658;12758.61724148277;12803.231806091664;12884.098113132084;10947.397893115876;11130.833037504444;11228.214025018253;11338.263012904135;11414.94292528532;3128.71841534263;4000.239954408653;4100.7334523255895;4210.3684447332835;4214.557471465513;48442.757786211194;49699;50701.74649126767;60622.696886515725;62053.37946620566;10350.689479315633;10494.632687855888;10629.787404251872;10661.573537058432;10752.193585480964;18727.719084213746;18754;18888;18951.715724264148;18961.43115706531;7051.224365319811;7082.327178917994;7134.644387701747;7137.785866424016;7139.643017849086;3712.7586706863967;3869.690424766012;3876.282887665791;3898.083950271684;3937.2322397132284;2793.608894754736;2836.0640988473756;2843.45974264889;2863.627728395337;2867.068202834087;489.307629703972;495.0890361734426;498.58617347601404;499.0791988780694;506.2204205523453;544.0533471759111;552.9447055294438;561.9662820230799;597.8953683105439;597.9850503737441;7976.282134607914;8014.79963000918;8085.393595480363;8130.3495720342235;8286.295664868554;492.9186684197139;570.2501210907643;591.6391001489088;597.8344664998841;604.0154548086591;3752.3955157930404;3950.20995800841;3984.581618929971;4043.6765058795813;4118.567550407224;49357.753211233714;51273.743631281606;52467.475325247025;58904;63120.05319920205;48577.27134092992;51118.74440627848;58098.7095064522;58711.41288587145;60370.698146508985;50730.23904641433;53132.468675313525;53178.73410632922;54417;54516.45483545193;7496.887546686913;7510.849783004419;7726;7787.454878158701;7854.567998760123;35534;37231.627683722814;37991;38111.61888381081;38722.80638596789;46501.767491162325;46502.302465462366;48638.27042594435;50203;50756.73108172325;9072.773180670536;9313.25493960495;9368.67209647659;9449.338546301693;9667.661631842851;10892.128629709447;10922.890771092187;11089.22375433712;11179.496922638338;11243.156763242627;10329;10499.632512862014;10661;10817.400997243263;10919.945400272947;9646.228301735984;9906.504674766376;11229.101671866394;11279.09767218604;11318.037966772916;11686.357250351311;11836.70408239801;12370;12620.43208055658;12727.23636581808;9837.754056148367;10174;10308.896911031094;10523.94738026305;10666.46667666629;9447.669331573363;10240.129588985019;10349.9482502587;10378;10493.317934334244 08:26:30 INFO - PROCESS | 23876 | __end_tp_report 08:26:30 INFO - PROCESS | 23876 | __start_cc_report 08:26:30 INFO - PROCESS | 23876 | _x_x_mozilla_cycle_collect,1543 08:26:30 INFO - PROCESS | 23876 | __end_cc_report 08:26:30 INFO - PROCESS | 23876 | __startTimestamp1481300790969__endTimestamp 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | ------- Summary: start ------- 08:26:30 INFO - PROCESS | 23876 | Number of tests: 6 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#0] dojo.html Cycles:165 Average:4521.30 Median:4670.63 stddev:470.26 (10.1%) stddev-sans-first:443.58 08:26:30 INFO - PROCESS | 23876 | Values: 2479.0 2566.7 2586.8 2599.5 2606.7 4422.9 4512.2 4518.8 4543.3 4549.5 4539.4 4560.4 4588.5 4598.1 4670.6 4131.7 4135.3 4184.6 4234.5 4317.2 4239.7 4323.2 4323.4 4351.6 4400.0 4125.9 4252.5 4262.1 4317.4 4355.5 4152.8 4210.8 4212.6 4222.0 4222.1 4194.7 4197.2 4198.9 4200.2 4207.1 4599.4 4630.8 4640.9 4659.4 4714.2 4917.3 4933.2 4939.7 4973.4 4983.6 4757.2 4939.7 4956.0 4957.1 4994.4 4476.5 4478.9 4509.9 4535.3 4605.0 4971.2 4972.1 4977.6 4978.4 4993.7 4803.2 4804.8 4812.7 4813.3 4857.1 4751.5 4804.3 4809.1 4861.5 4865.3 4778.8 4812.0 4835.9 4877.1 4883.5 4842.9 4844.9 4845.6 4871.5 4902.5 4052.0 4060.0 4064.6 4068.6 4098.5 3513.1 3543.2 3548.3 3605.3 3613.3 4246.0 4256.8 4284.9 4287.1 4287.9 4864.9 4877.0 4879.6 4880.3 4885.5 4250.4 4261.5 4263.4 4283.0 4295.0 4343.2 4355.2 4355.2 4355.9 4392.4 4516.8 4640.3 4640.5 4648.4 4695.7 4712.2 4781.6 4825.3 4860.9 4880.5 4630.7 4631.9 4633.7 4662.0 4675.2 4817.8 4824.2 4827.2 4828.9 4867.1 4786.7 4822.7 4828.8 4872.5 4894.3 4730.0 4740.4 4749.6 4786.5 4820.5 4785.5 4792.0 4792.4 4794.1 4855.3 4568.7 4743.0 4748.7 4758.3 4794.1 4701.7 4756.2 4783.0 4804.0 4829.8 4720.1 4727.5 4730.2 4766.5 4770.6 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#1] ext.html Cycles:180 Average:40053.79 Median:18768.97 stddev:66405.37 (353.8%) stddev-sans-first:66525.38 08:26:30 INFO - PROCESS | 23876 | Values: 541.8 549.1 550.1 559.5 572.6 26672.3 39181.8 41021.4 41097.2 41192.0 38170.8 38212.6 38563.8 38572.0 38678.6 7340.8 7495.8 7506.1 7510.5 7513.8 7303.8 7507.3 9523.2 9793.2 9795.6 9747.0 9844.4 9848.7 9848.9 9852.0 9775.6 9856.3 9856.6 9863.9 9865.9 9722.2 9801.2 9801.4 9805.2 9809.9 36072.0 36319.6 36453.8 36486.0 36503.8 3091.6 3133.0 3213.5 3298.3 3566.5 26863.2 26876.7 26953.6 27137.5 27677.6 3279.5 3376.0 3409.8 3410.6 3483.4 221204.0 221310.0 222067.0 222125.0 222449.0 49480.8 49916.8 50103.2 50121.0 50126.5 46589.5 51182.2 51603.3 51762.7 51769.7 12537.2 13168.3 13180.7 13189.6 13190.1 8929.2 9189.9 9995.8 10057.8 10062.6 14573.7 15014.6 15188.2 15551.4 15673.1 5851.0 5941.7 6018.4 6064.1 6073.8 772.6 894.4 930.5 953.7 1002.3 25864.4 27091.6 27502.4 27557.3 27598.2 840.8 906.1 929.9 982.2 984.4 13462.9 14330.6 14351.3 14487.8 14653.0 319762.0 320415.0 320538.0 322451.0 323203.0 46098.5 46143.0 46181.8 46214.0 46215.3 1071.9 1109.5 1142.6 1180.9 1199.7 24216.4 27647.6 28099.3 28243.3 28416.7 39264.2 39765.8 39807.2 39828.6 39889.8 200820.0 201302.0 201809.0 202693.0 203114.0 22050.8 22705.0 22787.9 24056.3 34127.3 34794.3 35272.3 35447.8 35805.8 36159.5 30215.8 30271.0 30527.0 30645.0 31062.8 29762.6 30005.8 30130.9 30394.2 30451.8 30733.0 30949.8 30970.5 31267.8 31294.5 25734.7 27375.0 28088.7 28435.0 28524.9 21804.2 22077.4 22361.1 22417.8 22441.0 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#2] jquery.html Cycles:180 Average:21041.12 Median:5007.90 stddev:54459.40 (1087.5%) stddev-sans-first:54589.98 08:26:30 INFO - PROCESS | 23876 | Values: 335.6 350.3 353.4 355.3 369.9 12357.8 15136.0 15408.0 16802.6 16986.8 16183.5 16290.0 16528.6 16629.9 16694.8 4705.8 4731.9 4907.3 4944.8 4953.6 4824.6 4826.7 4873.1 4873.4 4880.4 3604.3 3792.0 5298.5 5516.1 5539.9 5397.8 5613.7 5614.2 5617.2 5619.4 4991.1 5024.7 5033.2 5035.6 5036.2 18837.1 19457.1 19484.7 19776.0 19840.4 1913.3 2075.9 2163.1 2307.8 2405.9 11748.9 12800.7 13002.2 13125.9 13302.9 1648.5 1698.1 1730.5 1747.1 1769.2 60131.4 60984.0 62230.0 62384.7 62485.0 8933.4 9014.3 9103.7 9313.9 9562.1 12622.7 12623.1 12626.1 12626.7 12630.0 6490.3 6628.7 6683.9 6756.8 6855.3 6335.9 6360.3 6526.7 6527.1 6545.5 5757.8 5889.9 5938.2 5949.1 5981.6 1650.0 1673.7 1700.0 1709.8 1756.5 751.0 802.1 822.3 828.6 840.2 15936.8 16085.3 16145.4 16464.3 17088.6 857.2 881.7 894.5 906.8 907.7 9052.8 9656.1 9722.1 9754.1 9802.3 326719.0 331643.0 332069.0 333397.0 334509.0 36479.6 44155.1 60661.0 61342.7 62170.7 378.3 388.7 412.8 413.2 883.3 8319.7 8716.3 8997.6 9143.6 9579.2 31804.0 32971.5 33032.3 33679.3 34029.3 40306.8 40321.6 41488.8 42019.2 42037.6 7113.6 7203.1 7258.8 7558.5 7588.0 9396.3 10534.4 10598.5 10757.4 10839.6 9416.0 9514.5 9687.9 9723.4 10016.8 9465.5 9655.6 9683.0 9891.5 9923.7 9184.1 9440.0 9441.2 9538.2 9712.9 7290.9 7452.2 7584.5 7600.0 7611.2 8546.4 9777.8 9818.8 10143.8 10302.3 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#3] mootools.html Cycles:180 Average:4728.34 Median:5816.73 stddev:2557.21 (44.0%) stddev-sans-first:2551.65 08:26:30 INFO - PROCESS | 23876 | Values: 1332.6 1354.3 1428.7 1612.9 1628.8 6813.5 7210.4 7221.0 7233.9 7240.1 5970.4 6237.8 6801.8 7086.6 7100.6 2061.1 2088.1 2092.1 2093.0 2096.1 2105.3 2133.1 2151.6 2153.0 2153.6 1788.7 1879.0 1984.8 2070.6 2080.9 2020.8 2044.6 2045.1 2047.2 2047.4 2066.6 2103.6 2132.4 2171.1 2175.6 7263.3 7264.7 7267.4 7282.5 7297.8 4231.0 4344.0 4362.7 4367.2 4370.1 2538.9 2606.8 2682.4 2697.5 2708.2 803.9 817.3 840.2 907.6 911.3 9651.7 9702.8 9719.5 9719.8 9730.1 3702.5 3822.1 3849.9 3907.0 4039.2 8372.0 8383.0 8385.7 8388.6 8412.1 4319.8 4393.1 4397.5 4425.3 4438.4 3686.2 4015.0 4015.9 4020.4 4022.9 3356.2 3391.8 3458.9 3475.3 3574.8 1121.0 1132.2 1148.2 1159.0 1160.8 616.5 728.6 854.0 866.4 871.9 4695.9 4762.5 4816.0 4861.9 5386.8 813.0 816.8 842.3 861.7 886.1 3308.6 3408.7 3459.5 3473.2 3547.2 8554.4 8657.0 8669.6 8718.0 8805.3 8254.3 8576.8 8596.1 8772.3 8786.0 6679.1 6807.1 6816.0 6819.4 6866.8 4990.3 5095.6 5178.3 5217.9 5336.4 7750.7 7804.8 7875.0 7910.6 7971.1 7828.7 7968.3 8040.2 8107.6 8153.6 5967.9 6110.6 6121.4 6179.1 6208.8 6340.0 6344.7 6347.3 6377.9 6466.3 6089.4 6116.4 6179.0 6181.4 6265.1 5919.6 6079.1 6137.4 6165.7 6274.3 5791.2 5842.2 5844.4 5900.9 5909.9 4426.0 4432.2 4479.1 4540.8 4647.3 4309.5 4331.7 4345.0 4408.3 4415.1 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#4] prototype.html Cycles:180 Average:4028.16 Median:4623.23 stddev:1137.17 (24.6%) stddev-sans-first:1105.82 08:26:30 INFO - PROCESS | 23876 | Values: 322.5 329.1 338.3 342.6 344.2 4637.0 4731.7 4738.1 4740.5 4756.9 4423.8 4682.6 4695.8 4698.1 4730.8 3707.6 3716.3 3776.5 3791.7 3821.8 3785.1 3795.2 3802.4 3817.8 3818.8 3597.2 3775.9 3778.2 3782.7 3787.3 3687.8 3744.1 3790.9 3796.4 3809.7 3355.0 3412.5 3422.2 3426.1 3426.4 4545.6 4572.1 4584.0 4587.6 4601.0 3976.4 4101.5 4126.6 4278.7 4320.3 4849.3 4852.4 4861.9 4869.9 4872.6 3432.2 3436.6 3440.0 3466.8 3471.5 4881.0 5081.4 5092.0 5116.6 5127.8 3474.8 3500.2 3507.6 3515.6 3534.8 3552.9 3554.8 3555.1 3571.1 3594.7 4078.6 4096.3 4099.4 4100.5 4109.0 4767.6 4880.3 4882.9 4909.3 4917.9 1202.8 1210.5 1211.2 1215.3 1218.0 781.7 803.8 827.6 837.4 927.0 4211.8 4212.9 4219.6 4267.3 4274.0 4855.6 4889.4 4896.6 4915.7 4922.7 4128.7 4254.5 4270.6 4291.5 4303.8 2574.9 2575.9 2586.2 2589.6 2601.4 4624.5 4630.2 4636.9 4651.4 4668.6 4769.8 5000.3 5005.5 5027.3 5055.3 4665.8 4670.7 4672.5 4681.4 4690.4 4922.3 4929.5 4932.4 4935.4 4935.9 4610.2 4791.0 4797.9 4806.9 4815.5 4600.6 4622.0 4631.4 4631.7 4636.6 3728.7 3729.5 3732.8 3744.3 3780.2 4887.4 4905.3 4908.5 4910.3 4946.8 4573.2 4717.2 4735.6 4742.4 4753.8 4652.4 4672.2 4673.7 4680.4 4703.6 4640.8 4645.2 4650.1 4656.8 4668.3 4593.1 4642.9 4764.4 4768.9 4770.4 4639.5 4649.6 4650.8 4660.5 4674.6 08:26:30 INFO - PROCESS | 23876 | 08:26:30 INFO - PROCESS | 23876 | [#5] yui.html Cycles:180 Average:14280.09 Median:24563.79 stddev:17265.43 (70.3%) stddev-sans-first:17283.44 08:26:30 INFO - PROCESS | 23876 | Values: 630.8 644.8 659.1 728.9 746.7 12402.4 13518.5 13524.6 13551.4 13558.7 13139.6 13190.9 13207.6 13325.9 13332.0 622.5 636.4 762.6 767.2 781.0 766.1 787.1 787.7 787.8 788.8 823.7 827.0 827.0 827.4 827.5 646.7 661.4 782.6 810.3 810.7 597.5 599.4 601.6 602.0 602.1 12186.6 12200.6 12213.4 12292.5 12304.1 12593.8 12682.7 12758.6 12803.2 12884.1 10947.4 11130.8 11228.2 11338.3 11414.9 3128.7 4000.2 4100.7 4210.4 4214.6 48442.8 49699.0 50701.7 60622.7 62053.4 10350.7 10494.6 10629.8 10661.6 10752.2 18727.7 18754.0 18888.0 18951.7 18961.4 7051.2 7082.3 7134.6 7137.8 7139.6 3712.8 3869.7 3876.3 3898.1 3937.2 2793.6 2836.1 2843.5 2863.6 2867.1 489.3 495.1 498.6 499.1 506.2 544.1 552.9 562.0 597.9 598.0 7976.3 8014.8 8085.4 8130.3 8286.3 492.9 570.3 591.6 597.8 604.0 3752.4 3950.2 3984.6 4043.7 4118.6 49357.8 51273.7 52467.5 58904.0 63120.1 48577.3 51118.7 58098.7 58711.4 60370.7 50730.2 53132.5 53178.7 54417.0 54516.5 7496.9 7510.8 7726.0 7787.5 7854.6 35534.0 37231.6 37991.0 38111.6 38722.8 46501.8 46502.3 48638.3 50203.0 50756.7 9072.8 9313.3 9368.7 9449.3 9667.7 10892.1 10922.9 11089.2 11179.5 11243.2 10329.0 10499.6 10661.0 10817.4 10919.9 9646.2 9906.5 11229.1 11279.1 11318.0 11686.4 11836.7 12370.0 12620.4 12727.2 9837.8 10174.0 10308.9 10523.9 10666.5 9447.7 10240.1 10349.9 10378.0 10493.3 08:26:30 INFO - PROCESS | 23876 | -------- Summary: end -------- 08:26:30 INFO - PROCESS | 23876 | 08:26:32 INFO - TEST-INFO | 23876: exit 0 08:26:32 INFO - TEST-OK | dromaeo_css | took 1123426ms 08:26:32 INFO - TEST-START | kraken 08:26:32 INFO - operating with platform_type : linux_ 08:26:32 INFO - Initialising browser for kraken test... 08:26:32 INFO - TEST-INFO | started process 24182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpye0HEP/profile http://localhost:45429/getInfo.html) 08:26:36 INFO - PROCESS | 24182 | __metrics Screen width/height:1600/1200 08:26:36 INFO - PROCESS | 24182 | colorDepth:24 08:26:36 INFO - PROCESS | 24182 | Browser inner width/height: 1024/697 08:26:36 INFO - PROCESS | 24182 | __metrics 08:26:36 INFO - PROCESS | 24182 | 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:26:36 INFO - PROCESS | 24182 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:26:37 INFO - PROCESS | 24182 | console.error: PushService: 08:26:37 INFO - PROCESS | 24182 | stateChangeProcessEnqueue: Error transitioning state 08:26:37 INFO - PROCESS | 24182 | UnknownError 08:26:38 INFO - TEST-INFO | 24182: exit 0 08:26:38 INFO - Browser initialized. 08:26:38 INFO - Running cycle 1/1 for kraken test... 08:26:38 INFO - TEST-INFO | started process 24265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpye0HEP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:50 INFO - PROCESS | 24265 | RSS: Main: 174358528 08:26:50 INFO - PROCESS | 24265 | 08:26:53 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45429/tests/kraken/driver.html?testName=audio-beat-detection) 08:26:54 INFO - PROCESS | 24265 | RSS: Main: 239820800 08:26:54 INFO - PROCESS | 24265 | 08:26:56 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45429/tests/kraken/driver.html?testName=audio-dft) 08:26:57 INFO - PROCESS | 24265 | RSS: Main: 217989120 08:26:57 INFO - PROCESS | 24265 | 08:27:00 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45429/tests/kraken/driver.html?testName=audio-fft) 08:27:01 INFO - PROCESS | 24265 | RSS: Main: 300056576 08:27:01 INFO - PROCESS | 24265 | 08:27:03 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45429/tests/kraken/driver.html?testName=audio-oscillator) 08:27:04 INFO - PROCESS | 24265 | RSS: Main: 212013056 08:27:04 INFO - PROCESS | 24265 | 08:27:06 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45429/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:06 INFO - PROCESS | 24265 | RSS: Main: 215412736 08:27:06 INFO - PROCESS | 24265 | 08:27:10 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45429/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:10 INFO - PROCESS | 24265 | RSS: Main: 253972480 08:27:10 INFO - PROCESS | 24265 | 08:27:14 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45429/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:14 INFO - PROCESS | 24265 | RSS: Main: 248123392 08:27:14 INFO - PROCESS | 24265 | 08:27:17 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45429/tests/kraken/driver.html?testName=json-parse-financial) 08:27:18 INFO - PROCESS | 24265 | RSS: Main: 249942016 08:27:18 INFO - PROCESS | 24265 | 08:27:20 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45429/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:20 INFO - PROCESS | 24265 | RSS: Main: 213794816 08:27:20 INFO - PROCESS | 24265 | 08:27:22 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:23 INFO - PROCESS | 24265 | RSS: Main: 221147136 08:27:23 INFO - PROCESS | 24265 | 08:27:25 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:26 INFO - PROCESS | 24265 | RSS: Main: 224993280 08:27:26 INFO - PROCESS | 24265 | 08:27:29 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:27:29 INFO - PROCESS | 24265 | RSS: Main: 224104448 08:27:29 INFO - PROCESS | 24265 | 08:27:32 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:27:33 INFO - PROCESS | 24265 | RSS: Main: 227082240 08:27:33 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | Cycle 1(1): loaded http://localhost:45429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45429/tests/kraken/driver.html?testName=ai-astar) 08:27:35 INFO - PROCESS | 24265 | RSS: Main: 280055808 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | __start_tp_report 08:27:35 INFO - PROCESS | 24265 | _x_x_mozilla_page_load 08:27:35 INFO - PROCESS | 24265 | _x_x_mozilla_page_load_details 08:27:35 INFO - PROCESS | 24265 | |i|pagename|runs| 08:27:35 INFO - PROCESS | 24265 | |0;ai-astar;107;115;114;115;115;117;115;114;118;114 08:27:35 INFO - PROCESS | 24265 | |1;audio-beat-detection;148;134;133;164;131;130;133;129;151;128 08:27:35 INFO - PROCESS | 24265 | |2;audio-dft;163;162;162;162;162;161;162;163;165;161 08:27:35 INFO - PROCESS | 24265 | |3;audio-fft;77;77;75;107;76;75;75;76;110;78 08:27:35 INFO - PROCESS | 24265 | |4;audio-oscillator;85;84;104;85;85;85;110;87;86;114 08:27:35 INFO - PROCESS | 24265 | |5;imaging-gaussian-blur;128;126;127;127;126;126;127;127;128;127 08:27:35 INFO - PROCESS | 24265 | |6;imaging-darkroom;147;147;146;147;146;146;147;147;147;146 08:27:35 INFO - PROCESS | 24265 | |7;imaging-desaturate;96;96;96;96;95;95;95;95;95;95 08:27:35 INFO - PROCESS | 24265 | |8;json-parse-financial;66;64;65;65;66;66;66;66;66;65 08:27:35 INFO - PROCESS | 24265 | |9;json-stringify-tinderbox;65;66;66;65;67;66;66;66;65;65 08:27:35 INFO - PROCESS | 24265 | |10;stanford-crypto-aes;90;90;90;93;90;89;91;91;124;94 08:27:35 INFO - PROCESS | 24265 | |11;stanford-crypto-ccm;142;141;140;144;140;145;142;141;160;139 08:27:35 INFO - PROCESS | 24265 | |12;stanford-crypto-pbkdf2;187;187;183;186;181;180;185;183;188;192 08:27:35 INFO - PROCESS | 24265 | |13;stanford-crypto-sha256-iterative;69;72;69;69;69;69;69;68;67;67 08:27:35 INFO - PROCESS | 24265 | __end_tp_report 08:27:35 INFO - PROCESS | 24265 | __start_cc_report 08:27:35 INFO - PROCESS | 24265 | _x_x_mozilla_cycle_collect,1976 08:27:35 INFO - PROCESS | 24265 | __end_cc_report 08:27:35 INFO - PROCESS | 24265 | __startTimestamp1481300855608__endTimestamp 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | ------- Summary: start ------- 08:27:35 INFO - PROCESS | 24265 | Number of tests: 14 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:2.91 (2.5%) stddev-sans-first:1.39 08:27:35 INFO - PROCESS | 24265 | Values: 107.0 115.0 114.0 115.0 115.0 117.0 115.0 114.0 118.0 114.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:133.50 stddev:12.04 (9.0%) stddev-sans-first:12.23 08:27:35 INFO - PROCESS | 24265 | Values: 148.0 134.0 133.0 164.0 131.0 130.0 133.0 129.0 151.0 128.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 08:27:35 INFO - PROCESS | 24265 | Values: 163.0 162.0 162.0 162.0 162.0 161.0 162.0 163.0 165.0 161.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:13.70 (18.0%) stddev-sans-first:14.39 08:27:35 INFO - PROCESS | 24265 | Values: 77.0 77.0 75.0 107.0 76.0 75.0 75.0 76.0 110.0 78.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:85.00 stddev:11.88 (14.0%) stddev-sans-first:12.29 08:27:35 INFO - PROCESS | 24265 | Values: 85.0 84.0 104.0 85.0 85.0 85.0 110.0 87.0 86.0 114.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#5] imaging-gaussian-blur Cycles:10 Average:126.90 Median:127.00 stddev:0.74 (0.6%) stddev-sans-first:0.67 08:27:35 INFO - PROCESS | 24265 | Values: 128.0 126.0 127.0 127.0 126.0 126.0 127.0 127.0 128.0 127.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:27:35 INFO - PROCESS | 24265 | Values: 147.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 147.0 146.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:27:35 INFO - PROCESS | 24265 | Values: 96.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 08:27:35 INFO - PROCESS | 24265 | Values: 66.0 64.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 65.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 08:27:35 INFO - PROCESS | 24265 | Values: 65.0 66.0 66.0 65.0 67.0 66.0 66.0 66.0 65.0 65.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:90.50 stddev:10.58 (11.7%) stddev-sans-first:11.11 08:27:35 INFO - PROCESS | 24265 | Values: 90.0 90.0 90.0 93.0 90.0 89.0 91.0 91.0 124.0 94.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:142.00 stddev:6.11 (4.3%) stddev-sans-first:6.46 08:27:35 INFO - PROCESS | 24265 | Values: 142.0 141.0 140.0 144.0 140.0 145.0 142.0 141.0 160.0 139.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:186.50 stddev:3.58 (1.9%) stddev-sans-first:3.74 08:27:35 INFO - PROCESS | 24265 | Values: 187.0 187.0 183.0 186.0 181.0 180.0 185.0 183.0 188.0 192.0 08:27:35 INFO - PROCESS | 24265 | 08:27:35 INFO - PROCESS | 24265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.40 (2.0%) stddev-sans-first:1.48 08:27:35 INFO - PROCESS | 24265 | Values: 69.0 72.0 69.0 69.0 69.0 69.0 69.0 68.0 67.0 67.0 08:27:35 INFO - PROCESS | 24265 | -------- Summary: end -------- 08:27:35 INFO - PROCESS | 24265 | 08:27:36 INFO - TEST-INFO | 24265: exit 0 08:27:36 INFO - TEST-OK | kraken | took 64883ms 08:27:36 INFO - SUITE-END | took 1188s 08:27:37 INFO - Completed test suite (00:19:48) 08:27:37 INFO - javascript benchmark 08:27:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2478.987605061975, 2566.704828944672, 2586.7930565554757, 2599.4930988457245, 2606.7262937391574, 4422.911541769163, 4512.16524942885, 4518.774061296938, 4543.341215523747, 4549.5450454954525, 4539.364488971547, 4560.429946256718, 4588.518205588415, 4598.149342371664, 4670.626349892009, 4131.73143745657, 4135.31767258402, 4184.623383895449, 4234.491860976672, 4317.222899878021, 4239.745615263078, 4323.178596066753, 4323.437953066097, 4351.608355248027, 4400.031992961543, 4125.876223713293, 4252.489701235841, 4262.126264115849, 4317.352397140423, 4355.499117601485, 4152.8131234094535, 4210.831566737327, 4212.641925436342, 4221.97889010553, 4222.071144348239, 4194.706370554062, 4197.202531519001, 4198.85304014358, 4200.180964711883, 4207.137536804948, 4599.379083823675, 4630.836617171829, 4640.86077417678, 4659.44086709597, 4714.151452738506, 4917.336159618441, 4933.210686290212, 4939.728314942677, 4973.378327709036, 4983.551480366748, 4757.215059515176, 4939.654224204307, 4956.0, 4957.132501812204, 4994.35073440454, 4476.529964353728, 4478.9328160076975, 4509.909801803946, 4535.25168347219, 4605.032943081923, 4971.1797553403285, 4972.105021096165, 4977.626677999165, 4978.4025916890205, 4993.675411098339, 4803.207470767354, 4804.783784729696, 4812.663113582085, 4813.326134341195, 4857.101436234308, 4751.548602882721, 4804.25534042224, 4809.062232864628, 4861.489543597942, 4865.343178670905, 4778.808847646124, 4811.9518804811505, 4835.927461088017, 4877.073356062337, 4883.4628190898975, 4842.878928026757, 4844.927326090041, 4845.63657725672, 4871.464138944713, 4902.50974902514, 4052.0, 4059.9594004060173, 4064.573219811877, 4068.633822955889, 4098.508179018478, 3513.1217195701074, 3543.185067434453, 3548.27260411615, 3605.3330133925306, 3613.313470440608, 4246.0, 4256.808443620044, 4284.935725964113, 4287.056847493546, 4287.914241715211, 4864.927026094611, 4877.048975449753, 4879.634027447955, 4880.316755654211, 4885.5358740919555, 4250.426192463978, 4261.509926358446, 4263.360495925636, 4283.0, 4295.0, 4343.2182207203, 4355.194289056535, 4355.237833379209, 4355.869323920224, 4392.363107349416, 4516.751578663205, 4640.280756482751, 4640.535946405333, 4648.37246971668, 4695.718256904596, 4712.151812673752, 4781.641376896816, 4825.324454576432, 4860.902781944412, 4880.46314905353, 4630.745309008037, 4631.861044168681, 4633.675642705113, 4661.976690116528, 4675.205215113507, 4817.807287708452, 4824.179889418737, 4827.227643577011, 4828.903421931612, 4867.075255701406, 4786.664933454764, 4822.68652537584, 4828.830990915301, 4872.4640289567415, 4894.2658601210105, 4729.952700472952, 4740.3837501124635, 4749.620030397491, 4786.497417771084, 4820.493848146035, 4785.521447855326, 4792.041591681747, 4792.400949881265, 4794.137055330074, 4855.320255164209, 4568.70303430276, 4742.976285118552, 4748.691335063209, 4758.286257061469, 4794.137055329935, 4701.717896926195, 4756.191447453872, 4783.043391321819, 4804.0, 4829.782659780248, 4720.055988802322, 4727.456342520517, 4730.1958667026, 4766.547178018082, 4770.594499467584], "value": 4491.388942735356, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.8320320700582, 549.1488193300388, 550.0758725341425, 559.5104283751717, 572.6106247751528, 26672.333191670194, 39181.80409097954, 41021.384679229835, 41097.17805643889, 41192.0, 38170.809145954234, 38212.617873821255, 38563.807180964126, 38572.0, 38678.61321386785, 7340.816479588027, 7495.812604684872, 7506.09926808781, 7510.549367037968, 7513.774586762379, 7303.780886573385, 7507.324340809326, 9523.238140948708, 9793.167580755644, 9795.608175672964, 9747.0, 9844.3601165924, 9848.704538863845, 9848.901510984906, 9852.0, 9775.560099795526, 9856.260780441424, 9856.556454959544, 9863.901360986405, 9865.90134098657, 9722.173615242713, 9801.215902727765, 9801.411915285105, 9805.215582753433, 9809.852852207223, 36072.0, 36319.63680363189, 36453.81773091118, 36486.0, 36503.817480912694, 3091.629004519472, 3133.0444214514564, 3213.5179723041497, 3298.340331933623, 3566.4828599852876, 26863.194104176906, 26876.73123268762, 26953.55840862254, 27137.4544590527, 27677.58483622727, 3279.5199053334395, 3375.9662403375896, 3409.8465569049204, 3410.6248312685584, 3483.4078206704667, 221204.0, 221310.0, 222067.0, 222125.0, 222449.0, 49480.75259623679, 49916.75041624768, 50103.24845127326, 50121.0, 50126.49873501218, 46589.5341046592, 51182.23226651603, 51603.25951107289, 51762.741186293824, 51769.74115129476, 12537.185082969578, 13168.341582920815, 13180.670483237804, 13189.604311870658, 13190.142640728322, 8929.24101451371, 9189.862152067724, 9995.800083998425, 10057.849132263022, 10062.597496100074, 14573.720912794537, 15014.624634384229, 15188.240587970557, 15551.377944882079, 15673.137977411352, 5850.95534430693, 5941.732622032019, 6018.398160183947, 6064.120702498201, 6073.787417440369, 772.6175543106199, 894.3605322194662, 930.5207817973749, 953.7377221264207, 1002.3284399452384, 25864.353391165372, 27091.593626095626, 27502.449951000868, 27557.31106722308, 27598.172054838386, 840.7813968368146, 906.1301150895215, 929.9116583924516, 982.1946004276425, 984.4313274967307, 13462.932685336511, 14330.57008289753, 14351.282435878164, 14487.78268325976, 14653.0, 319762.0, 320415.0, 320538.0, 322451.0, 323203.0, 46098.5390146101, 46143.0, 46181.76909115433, 46214.0, 46215.30677039847, 1071.908887744535, 1109.545086514525, 1142.6000899685043, 1180.8523934508187, 1199.6880810989205, 24216.362001798585, 27647.58528622032, 28099.297517562223, 28243.293917652223, 28416.715832841408, 39264.2147157061, 39765.80117099396, 39807.2038559233, 39828.60171398365, 39889.80055099706, 200820.0, 201302.0, 201809.0, 202693.0, 203114.0, 22050.779492204874, 22705.0, 22787.88606056959, 24056.27831165068, 34127.31745365128, 34794.304113917075, 35272.29455410826, 35447.82276088603, 35805.82097089498, 36159.45760813537, 30215.848920755256, 30271.0, 30527.0, 30645.0, 31062.844685776425, 29762.553561696157, 30005.849970750878, 30130.93232413596, 30394.240143996576, 30451.847740761154, 30733.0, 30949.845250774502, 30970.53544196794, 31267.84366078155, 31294.530582040832, 25734.70454941592, 27375.0, 28088.71911280861, 28435.0, 28524.85737571299, 21804.236851710113, 22077.448063798533, 22361.105555777587, 22417.77582224222, 22441.0], "value": 16496.885810579977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.5771727623194, 350.2906614106434, 353.44332676035236, 355.2646022732942, 369.88533554598075, 12357.752844943096, 15136.0, 15408.0, 16802.579935501617, 16986.83013169868, 16183.514494565152, 16290.02259864409, 16528.58678533037, 16629.91685041576, 16694.83305166951, 4705.764711764416, 4731.881702957437, 4907.337509436234, 4944.752762361885, 4953.603711703073, 4824.6381521385865, 4826.710397376151, 4873.098476781789, 4873.390826146731, 4880.414350277972, 3604.3151801157696, 3791.9810400948095, 5298.496642818926, 5516.117421212607, 5539.889202215953, 5397.8380648580605, 5613.66318020918, 5614.242077319591, 5617.185508101344, 5619.4099619540175, 4991.101601711691, 5024.698518088926, 5033.219850923113, 5035.647504674675, 5036.219385995177, 18837.058147092728, 19457.124429400712, 19484.70772938407, 19776.011199440116, 19840.404787856387, 1913.2596220024889, 2075.937721868346, 2163.0806907064434, 2307.8384513084093, 2405.8990985291784, 11748.941255293754, 12800.743985120338, 13002.219866808022, 13125.934370328087, 13302.93348533251, 1648.5136884619067, 1698.1339516846374, 1730.5327561558436, 1747.1177055586975, 1769.2392271323326, 60131.39868601302, 60984.0, 62230.0, 62384.68807655932, 62485.0, 8933.374663773473, 9014.278857691368, 9103.726888193365, 9313.860292095624, 9562.139407453362, 12622.747545049047, 12623.053271004712, 12626.053046021585, 12626.747465050832, 12630.0, 6490.318516555788, 6628.740539297518, 6683.899741503881, 6756.763513277012, 6855.314468553204, 6335.873282534323, 6360.268569114611, 6526.673666316665, 6527.118838956775, 6545.476361891037, 5757.769689212385, 5889.852753681193, 5938.164404792694, 5949.10763385487, 5981.611195272293, 1649.983500164983, 1673.7238355671177, 1700.0, 1709.7606335113094, 1756.4730580825956, 750.9962450187714, 802.0896282719156, 822.2558584480935, 828.6105530400702, 840.2018082821344, 15936.760948585781, 16085.276162572713, 16145.434909778101, 16464.341426343053, 17088.57278568046, 857.1557016338901, 881.7354793561906, 894.5437826708295, 906.7551761024619, 907.7095329494631, 9052.81894362109, 9656.130948214695, 9722.076402741728, 9754.0733630305, 9802.256941858624, 326719.0, 331643.0, 332069.0, 333397.0, 334509.0, 36479.635203648155, 44155.116897661865, 60661.0, 61342.69328653328, 62170.68914655398, 378.3360202843991, 388.7084686485136, 412.76693090380894, 413.2066432449704, 883.3242569434387, 8319.667213311643, 8716.302695784449, 8997.55012249398, 9143.63425462974, 9579.185769209416, 31804.0, 32971.50542741813, 33032.33935321328, 33679.326413471106, 34029.31941361109, 40306.79846600748, 40321.59678403178, 41488.79255603703, 42019.1596168081, 42037.57962420337, 7113.608751518716, 7203.135623725187, 7258.818529536804, 7558.546487210785, 7588.0, 9396.342256042011, 10534.42060686639, 10598.470076496298, 10757.354558726502, 10839.620613278497, 9416.0, 9514.476703781082, 9687.854682179914, 9723.367981081206, 10016.799664006534, 9465.47939863314, 9655.565499552396, 9683.0, 9891.455969921722, 9923.702288931343, 9184.12750788674, 9440.0, 9441.15029647335, 9538.189253913495, 9712.854307185538, 7290.854182916418, 7452.180260171485, 7584.5070070445245, 7600.0, 7611.200823913409, 8546.35902307317, 9777.804443911224, 9818.803623927624, 10143.797124057624, 10302.33034852732], "value": 7587.591975449271, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.573576455534, 1354.3093022558496, 1428.692831041326, 1612.9435469758562, 1628.8126865410475, 6813.523053386265, 7210.423166146708, 7221.025161603196, 7233.855322893545, 7240.13118425789, 5970.373110823355, 6237.750489980395, 6801.761938332159, 7086.645667716606, 7100.609466479342, 2061.0622166914054, 2088.0708084902226, 2092.0585736418598, 2093.0476633131966, 2096.0777258006506, 2105.2528024948283, 2133.0827744070043, 2151.612709712251, 2152.9784702153015, 2153.5800518898823, 1788.7495750594926, 1878.9812101878942, 1984.8312893404077, 2070.554830711397, 2080.947976300597, 2020.7676117246485, 2044.6115238104862, 2045.0694933805082, 2047.2322878920404, 2047.396018174642, 2066.5970135823522, 2103.621348157331, 2132.3816093332916, 2171.0989939175224, 2175.641019231825, 7263.3462988330775, 7264.673089710976, 7267.382272506843, 7282.490225684204, 7297.817554561126, 4230.957690423118, 4344.044310251739, 4362.738235705836, 4367.235733746614, 4370.082282720632, 2538.885750141248, 2606.8044896632637, 2682.3562345037244, 2697.5144473994674, 2708.214617760847, 803.863343231652, 817.2767101115496, 840.2185967050641, 907.5552979040289, 911.334725650279, 9651.71044868655, 9702.805943881154, 9719.46542940138, 9719.757006074835, 9730.12428881397, 3702.5186725725484, 3822.10180607557, 3849.942250866239, 3906.9609303907164, 4039.2331253091725, 8371.958140209383, 8382.958085209535, 8385.706500272583, 8388.580570971428, 8412.116727743622, 4319.805608747614, 4393.077453734688, 4397.472303323558, 4425.3140763181755, 4438.4451943507065, 3686.1521849974656, 4014.959850401517, 4015.8996025099605, 4020.3567429211184, 4022.899427514277, 3356.177736454584, 3391.8304084795664, 3458.9308213836084, 3475.252820643523, 3574.8033858137533, 1120.994395028036, 1132.2413982631492, 1148.1962626161721, 1159.049579344929, 1160.843286156375, 616.5067945643557, 728.5919884864387, 854.0, 866.3935245328296, 871.947683139001, 4695.906081878412, 4762.476127625959, 4815.975920120377, 4861.878453038702, 5386.811461598903, 812.959352032396, 816.848882956655, 842.3429724814654, 861.7457849934284, 886.0519244408421, 3308.6360500344945, 3408.693217610427, 3459.532963049956, 3473.1838018065732, 3547.166415892263, 8554.401191916506, 8656.956715216384, 8669.56652167389, 8718.041015488292, 8805.339599530062, 8254.298384637374, 8576.828463430822, 8596.097409771884, 8772.298216142693, 8786.0, 6679.064930909676, 6807.081044059088, 6816.0, 6819.386255237053, 6866.793996180123, 4990.251462280687, 5095.592352611856, 5178.275041494118, 5217.921731174111, 5336.359636843604, 7750.72113101332, 7804.804879877821, 7875.0, 7910.644021019183, 7971.123176450702, 7828.686852525835, 7968.282854543119, 8040.155783642634, 8107.635156417858, 8153.6330865112395, 5967.850803729942, 6110.633361998294, 6121.449069583581, 6179.073139029181, 6208.81373558794, 6339.9683001586545, 6344.714487847966, 6347.270063942521, 6377.87244255103, 6466.321036291121, 6089.391060893875, 6116.449519543263, 6178.969105154445, 6181.412765787243, 6265.122882796321, 5919.556033297434, 6079.088136779518, 6137.41694539018, 6165.691715414301, 6274.309825919237, 5791.218185545066, 5842.2405087338375, 5844.357120716632, 5900.881982360414, 5909.852253693692, 4426.0, 4432.246518091868, 4479.0817882333395, 4540.750258735801, 4647.326137710081, 4309.504406993111, 4331.675124365748, 4345.0, 4408.338749187647, 4415.116976604628], "value": 3858.159832081356, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.51622566150775, 329.08186160611893, 338.25414960013165, 342.5786282872066, 344.189433883205, 4637.0, 4731.692439991402, 4738.052389522097, 4740.478547359788, 4756.881077973044, 4423.800928958197, 4682.578567928886, 4695.779097434666, 4698.08387364464, 4730.763461826912, 3707.555093388797, 3716.2753263113705, 3776.527934008249, 3791.6587507124354, 3821.847126114966, 3785.1483416231267, 3795.1650636859986, 3802.429635554661, 3817.751846129993, 3818.7970789201313, 3597.244578638489, 3775.9056023599355, 3778.1876896467256, 3782.7352085354037, 3787.280416720828, 3687.760295580779, 3744.0905138379485, 3790.90522736931, 3796.4115562087923, 3809.695224382043, 3354.9832250838836, 3412.4540073588105, 3422.19578399076, 3426.126337783849, 3426.3832510148163, 4545.568171023748, 4572.0855828834365, 4583.9541604583865, 4587.587117159472, 4600.9769951150365, 3976.363781794899, 4101.487314085739, 4126.649234815044, 4278.657707383433, 4320.26555485565, 4849.272609108627, 4852.369192005017, 4861.927071093936, 4869.902601947942, 4872.634552408548, 3432.2277487565348, 3436.570428696413, 3439.9680095971107, 3466.757326987112, 3471.5313432686517, 4880.951190488121, 5081.390233172006, 5092.0, 5116.641835071547, 5127.8205262816, 3474.808885511321, 3500.177458297298, 3507.5615548056494, 3515.6132825389186, 3534.8055856928117, 3552.91117722059, 3554.751167418256, 3555.1289933966345, 3571.1072231942017, 3594.658507441789, 4078.6125318094732, 4096.3409819023345, 4099.364598487239, 4100.5489396166395, 4109.0, 4767.594754445841, 4880.341153944243, 4882.8779280517565, 4909.337239472697, 4917.852464426073, 1202.753435545711, 1210.4855436439477, 1211.2248161176854, 1215.3254943506438, 1217.9634610961684, 781.7185813107277, 803.8392321535716, 827.594478705442, 837.3761547647042, 927.0, 4211.810468528863, 4212.894677633022, 4219.599138081878, 4267.295896177158, 4273.957260427418, 4855.6115510759055, 4889.3888263967, 4896.632752543574, 4915.705057696549, 4922.680025798311, 4128.690348223895, 4254.468191476066, 4270.572942705705, 4291.463567054118, 4303.849365272263, 2574.8541898855206, 2575.858327791952, 2586.1594981631047, 2589.6374507568958, 2601.4375839797785, 4624.5144259852905, 4630.166570017429, 4636.930446043312, 4651.39516874425, 4668.5798278155125, 4769.809207631725, 5000.2749601307605, 5005.4961526930565, 5027.340977477424, 5055.3428054353435, 4665.76357265325, 4670.743109129031, 4672.532746725368, 4681.414823147106, 4690.437147542249, 4922.261660750916, 4929.457759646364, 4932.383452068491, 4935.360760884757, 4935.876603084952, 4610.193216187221, 4790.95209047919, 4797.880052998703, 4806.855794326176, 4815.518448155157, 4600.5629465200755, 4621.953780462152, 4631.421072365954, 4631.698939568917, 4636.6058884995155, 3728.738988270795, 3729.489556729529, 3732.77899649708, 3744.3447396704923, 3780.2250538640064, 4887.3890763654545, 4905.313256144214, 4908.509149085063, 4910.2880082388565, 4946.777395017161, 4573.245414506569, 4717.2216584263215, 4735.621150308036, 4742.359781429601, 4753.764021354542, 4652.371929789435, 4672.205725026822, 4673.719576825401, 4680.368150299666, 4703.600193983413, 4640.791164397678, 4645.2103142465185, 4650.116477869194, 4656.837010704609, 4668.346431499661, 4593.115797851121, 4642.930356044594, 4764.4282686077895, 4768.856934291976, 4770.403699537558, 4639.504693429226, 4649.558291962258, 4650.837290677331, 4660.533946605312, 4674.6260299176665], "value": 3677.187355186155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.7540059376847, 644.7839973608841, 659.1299484680223, 728.9357537994528, 746.652806445003, 12402.441890114944, 13518.526851560197, 13524.594262172126, 13551.390187441564, 13558.661033474167, 13139.605811825637, 13190.868091319084, 13207.603771886836, 13325.93337033316, 13332.0, 622.4771192198552, 636.360457739973, 762.5653377574785, 767.2327672327673, 781.0315209140681, 766.1342682768492, 787.1420152034282, 787.7361084036826, 787.8306164174701, 788.7515432638701, 823.7034667519688, 826.9503829770202, 826.9710560130396, 827.4332082523491, 827.4993628854546, 646.7025168422531, 661.3551787007677, 782.6282515805004, 810.2707563193114, 810.6595230003413, 597.5249676507187, 599.3706608061561, 601.5698775375628, 602.0367412140583, 602.0547740048127, 12186.634400967896, 12200.572979945659, 12213.389330533439, 12292.508299668003, 12304.07719421047, 12593.811092833615, 12682.682932926658, 12758.61724148277, 12803.231806091664, 12884.098113132084, 10947.397893115876, 11130.833037504444, 11228.214025018253, 11338.263012904135, 11414.94292528532, 3128.71841534263, 4000.239954408653, 4100.7334523255895, 4210.3684447332835, 4214.557471465513, 48442.757786211194, 49699.0, 50701.74649126767, 60622.696886515725, 62053.37946620566, 10350.689479315633, 10494.632687855888, 10629.787404251872, 10661.573537058432, 10752.193585480964, 18727.719084213746, 18754.0, 18888.0, 18951.715724264148, 18961.43115706531, 7051.224365319811, 7082.327178917994, 7134.644387701747, 7137.785866424016, 7139.643017849086, 3712.7586706863967, 3869.690424766012, 3876.282887665791, 3898.083950271684, 3937.2322397132284, 2793.608894754736, 2836.0640988473756, 2843.45974264889, 2863.627728395337, 2867.068202834087, 489.307629703972, 495.0890361734426, 498.58617347601404, 499.0791988780694, 506.2204205523453, 544.0533471759111, 552.9447055294438, 561.9662820230799, 597.8953683105439, 597.9850503737441, 7976.282134607914, 8014.79963000918, 8085.393595480363, 8130.3495720342235, 8286.295664868554, 492.9186684197139, 570.2501210907643, 591.6391001489088, 597.8344664998841, 604.0154548086591, 3752.3955157930404, 3950.20995800841, 3984.581618929971, 4043.6765058795813, 4118.567550407224, 49357.753211233714, 51273.743631281606, 52467.475325247025, 58904.0, 63120.05319920205, 48577.27134092992, 51118.74440627848, 58098.7095064522, 58711.41288587145, 60370.698146508985, 50730.23904641433, 53132.468675313525, 53178.73410632922, 54417.0, 54516.45483545193, 7496.887546686913, 7510.849783004419, 7726.0, 7787.454878158701, 7854.567998760123, 35534.0, 37231.627683722814, 37991.0, 38111.61888381081, 38722.80638596789, 46501.767491162325, 46502.302465462366, 48638.27042594435, 50203.0, 50756.73108172325, 9072.773180670536, 9313.25493960495, 9368.67209647659, 9449.338546301693, 9667.661631842851, 10892.128629709447, 10922.890771092187, 11089.22375433712, 11179.496922638338, 11243.156763242627, 10329.0, 10499.632512862014, 10661.0, 10817.400997243263, 10919.945400272947, 9646.228301735984, 9906.504674766376, 11229.101671866394, 11279.09767218604, 11318.037966772916, 11686.357250351311, 11836.70408239801, 12370.0, 12620.43208055658, 12727.23636581808, 9837.754056148367, 10174.0, 10308.896911031094, 10523.94738026305, 10666.46667666629, 9447.669331573363, 10240.129588985019, 10349.9482502587, 10378.0, 10493.317934334244], "value": 6156.194831191023, "unit": "score"}], "name": "dromaeo_css", "value": 6051.424663796288, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 114.0, 115.0, 115.0, 117.0, 115.0, 114.0, 118.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 134.0, 133.0, 164.0, 131.0, 130.0, 133.0, 129.0, 151.0, 128.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 162.0, 162.0, 161.0, 162.0, 163.0, 165.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 107.0, 76.0, 75.0, 75.0, 76.0, 110.0, 78.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 104.0, 85.0, 85.0, 85.0, 110.0, 87.0, 86.0, 114.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 127.0, 127.0, 126.0, 126.0, 127.0, 127.0, 128.0, 127.0], "value": 126.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 93.0, 90.0, 89.0, 91.0, 91.0, 124.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 140.0, 144.0, 140.0, 145.0, 142.0, 141.0, 160.0, 139.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 183.0, 186.0, 181.0, 180.0, 185.0, 183.0, 188.0, 192.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 69.0, 69.0, 69.0, 69.0, 69.0, 68.0, 67.0, 67.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1581.6000000000001, "alertThreshold": 2.0}]} 08:27:37 INFO - Return code: 0 08:27:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:27:37 INFO - # TBPL SUCCESS # 08:27:37 INFO - Running post-action listener: _resource_record_post_action 08:27:37 INFO - [mozharness: 2016-12-09 16:27:37.427851Z] Finished run-tests step (success) 08:27:37 INFO - Running post-run listener: _resource_record_post_run 08:27:37 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392167424; Read time: 12; Write time: 298852 08:27:37 INFO - TinderboxPrint: CPU usage
13.2% 08:27:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:27:37 INFO - TinderboxPrint: I/O write bytes / time
392,167,424 / 298,852 08:27:37 INFO - TinderboxPrint: CPU idle
8,345.0 (86.8%) 08:27:37 INFO - TinderboxPrint: CPU user
1,218.3 (12.7%) 08:27:37 INFO - TinderboxPrint: Swap in / out
0 / 0 08:27:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100040704; Read time: 0; Write time: 89452 08:27:37 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 292126720; Read time: 0; Write time: 209400 08:27:38 INFO - Running post-run listener: _upload_blobber_files 08:27:38 INFO - Blob upload gear active. 08:27:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:27:38 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:27:38 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:27:38 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:27:38 INFO - (blobuploader) - INFO - Open directory for files ... 08:27:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:27:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:27:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:39 INFO - (blobuploader) - INFO - Done attempting. 08:27:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:27:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:40 INFO - (blobuploader) - INFO - Done attempting. 08:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:27:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:48 INFO - (blobuploader) - INFO - Done attempting. 08:27:48 INFO - (blobuploader) - INFO - Iteration through files over. 08:27:48 INFO - Return code: 0 08:27:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:27:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:27:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94b043167602eaf2270c5dbaa38a1b2c19ebf3ec1f7e0e0582bc97ed0f596b854583eb2a35189d10b60b3ad7a996ceb6d1fe8d20b2594e426136e5fc5ce39cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a44f9085b188e76129f3abe1afa3a55f972e0054de03ef4e9da10095897023dc7447043b40223bf17ae99005ee8f27beee859ce5acce5ec687ac87903540b0b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85de6f65ab6e63ab6ad30e5ac5ba5de3336a444cadaa67858f8e33825701bdb6b4a8fb3abf0c024f2a7c727a146eff8f23dc8e82076deaba2d64454475971ada"} 08:27:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:27:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:27:48 INFO - Contents: 08:27:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b94b043167602eaf2270c5dbaa38a1b2c19ebf3ec1f7e0e0582bc97ed0f596b854583eb2a35189d10b60b3ad7a996ceb6d1fe8d20b2594e426136e5fc5ce39cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a44f9085b188e76129f3abe1afa3a55f972e0054de03ef4e9da10095897023dc7447043b40223bf17ae99005ee8f27beee859ce5acce5ec687ac87903540b0b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85de6f65ab6e63ab6ad30e5ac5ba5de3336a444cadaa67858f8e33825701bdb6b4a8fb3abf0c024f2a7c727a146eff8f23dc8e82076deaba2d64454475971ada"} 08:27:48 INFO - Running post-run listener: copy_logs_to_upload_dir 08:27:48 INFO - Copying logs to upload dir... 08:27:48 INFO - mkdir: /builds/slave/test/build/upload/logs 08:27:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.032934 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-12-09 08:27:48.377294) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 08:27:48.381353) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 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/b94b043167602eaf2270c5dbaa38a1b2c19ebf3ec1f7e0e0582bc97ed0f596b854583eb2a35189d10b60b3ad7a996ceb6d1fe8d20b2594e426136e5fc5ce39cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a44f9085b188e76129f3abe1afa3a55f972e0054de03ef4e9da10095897023dc7447043b40223bf17ae99005ee8f27beee859ce5acce5ec687ac87903540b0b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85de6f65ab6e63ab6ad30e5ac5ba5de3336a444cadaa67858f8e33825701bdb6b4a8fb3abf0c024f2a7c727a146eff8f23dc8e82076deaba2d64454475971ada"} build_url:https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011841 build_url: 'https://queue.taskcluster.net/v1/task/fNeTW14qTnqI8QTp-D-7TA/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/b94b043167602eaf2270c5dbaa38a1b2c19ebf3ec1f7e0e0582bc97ed0f596b854583eb2a35189d10b60b3ad7a996ceb6d1fe8d20b2594e426136e5fc5ce39cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a44f9085b188e76129f3abe1afa3a55f972e0054de03ef4e9da10095897023dc7447043b40223bf17ae99005ee8f27beee859ce5acce5ec687ac87903540b0b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85de6f65ab6e63ab6ad30e5ac5ba5de3336a444cadaa67858f8e33825701bdb6b4a8fb3abf0c024f2a7c727a146eff8f23dc8e82076deaba2d64454475971ada"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 08:27:48.407227) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:27:48.407555) ========= 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=c6927cf182745ad25b1d38b9000001fe-1481299612.394371-260265116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:27:48.492120) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 08:27:48.492463) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 08:27:48.492788) ========= ========= Total master_lag: 0.18 =========