builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1482080849.35 results: success (0) buildid: 20161218073118 builduid: 4ae02df267e24d428c1d3b2df2ec8418 revision: 2824deb82146bba070995a762cfc98ddb2d1decb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.346213) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.346710) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.347041) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.361736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.362052) ========= 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=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-18 09:07:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-12-18 09:07:29 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.481944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.482264) ========= 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=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015973 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.538659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:29.539503) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2824deb82146bba070995a762cfc98ddb2d1decb --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 2824deb82146bba070995a762cfc98ddb2d1decb --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=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-18 09:07:29,948 truncating revision to first 12 chars 2016-12-18 09:07:29,948 Setting DEBUG logging. 2016-12-18 09:07:29,948 attempt 1/10 2016-12-18 09:07:29,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2824deb82146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 09:07:30,435 unpacking tar archive at: mozilla-central-2824deb82146/testing/mozharness/ program finished with exit code 0 elapsedTime=0.794333 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:30.349155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:30.349482) ========= script_repo_revision: 2824deb82146bba070995a762cfc98ddb2d1decb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:30.349916) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:30.350347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 09:07:30.362049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-18 09:07:30.362716) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:30 INFO - MultiFileLogger online at 20161218 09:07:30 in /builds/slave/test 09:07:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:07:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:30 INFO - {'append_to_log': False, 09:07:30 INFO - 'base_work_dir': '/builds/slave/test', 09:07:30 INFO - 'blob_upload_branch': 'Firefox', 09:07:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:30 INFO - 'branch': 'Firefox', 09:07:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:30 INFO - 'config_files': ('talos/linux_config.py',), 09:07:30 INFO - 'default_actions': ('clobber', 09:07:30 INFO - 'read-buildbot-config', 09:07:30 INFO - 'download-and-extract', 09:07:30 INFO - 'populate-webroot', 09:07:30 INFO - 'create-virtualenv', 09:07:30 INFO - 'install', 09:07:30 INFO - 'run-tests'), 09:07:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:30 INFO - 'download_minidump_stackwalk': True, 09:07:30 INFO - 'download_symbols': 'ondemand', 09:07:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:30 INFO - 'installer_path': 'installer.exe', 09:07:30 INFO - 'log_level': 'info', 09:07:30 INFO - 'log_name': 'talos', 09:07:30 INFO - 'log_to_console': True, 09:07:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:30 INFO - 'opt_config_files': (), 09:07:30 INFO - 'pip_index': False, 09:07:30 INFO - 'sps_profile': False, 09:07:30 INFO - 'sps_profile_interval': 0, 09:07:30 INFO - 'suite': 'dromaeojs', 09:07:30 INFO - 'system_bits': '32', 09:07:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:30 INFO - 'title': 'talos-linux64-ix-021', 09:07:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:30 INFO - 'use_talos_json': True, 09:07:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:30 INFO - 'work_dir': 'build'} 09:07:30 INFO - [mozharness: 2016-12-18 17:07:30.872230Z] Running clobber step. 09:07:30 INFO - Running pre-action listener: _resource_record_pre_action 09:07:30 INFO - Running main action method: clobber 09:07:30 INFO - rmtree: /builds/slave/test/build 09:07:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:31 INFO - Running post-action listener: _resource_record_post_action 09:07:31 INFO - [mozharness: 2016-12-18 17:07:31.842293Z] Finished clobber step (success) 09:07:31 INFO - [mozharness: 2016-12-18 17:07:31.842393Z] Running read-buildbot-config step. 09:07:31 INFO - Running pre-action listener: _resource_record_pre_action 09:07:31 INFO - Running main action method: read_buildbot_config 09:07:31 INFO - Using buildbot properties: 09:07:31 INFO - { 09:07:31 INFO - "project": "", 09:07:31 INFO - "product": "firefox", 09:07:31 INFO - "script_repo_revision": "production", 09:07:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:07:31 INFO - "repository": "", 09:07:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:07:31 INFO - "buildid": "20161218073118", 09:07:31 INFO - "pgo_build": "True", 09:07:31 INFO - "basedir": "/builds/slave/test", 09:07:31 INFO - "buildnumber": 133, 09:07:31 INFO - "slavename": "talos-linux64-ix-021", 09:07:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:07:31 INFO - "platform": "ubuntu64_hw", 09:07:31 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:07:31 INFO - "branch": "mozilla-central", 09:07:31 INFO - "repo_path": "mozilla-central", 09:07:31 INFO - "revision": "2824deb82146bba070995a762cfc98ddb2d1decb", 09:07:31 INFO - "stage_platform": "linux64-pgo", 09:07:31 INFO - "builduid": "4ae02df267e24d428c1d3b2df2ec8418", 09:07:31 INFO - "slavebuilddir": "test" 09:07:31 INFO - } 09:07:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:07:31 INFO - Running post-action listener: _resource_record_post_action 09:07:31 INFO - [mozharness: 2016-12-18 17:07:31.844849Z] Finished read-buildbot-config step (success) 09:07:31 INFO - [mozharness: 2016-12-18 17:07:31.844938Z] Running download-and-extract step. 09:07:31 INFO - Running pre-action listener: _resource_record_pre_action 09:07:31 INFO - Running main action method: download_and_extract 09:07:31 INFO - mkdir: /builds/slave/test/build/tests 09: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')]} 09:07:31 INFO - trying https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:07:31 INFO - Downloading https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/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 09:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/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 09:07:32 INFO - Downloaded 1361 bytes. 09:07:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:07:32 INFO - Using the following test package requirements: 09:07:32 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:07:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:07:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'jsshell-linux-x86_64.zip'], 09:07:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:07:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:07:32 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:07:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:07:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:32 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:07:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:07:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:07:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:07:32 INFO - Fetch https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:07:33 INFO - Expected file size: 21828721 09:07:33 INFO - Obtained file size: 21828721 09:07:37 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 09:07:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:07:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:07:37 INFO - Fetch https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:07:38 INFO - Expected file size: 21828721 09:07:38 INFO - Obtained file size: 21828721 09:07:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:07:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:07:42 INFO - Fetch https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:07:43 INFO - Expected file size: 14409734 09:07:43 INFO - Obtained file size: 14409734 09:07:44 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')]} 09:07:44 INFO - trying https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:44 INFO - Downloading https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/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 09:07:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/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 09:07:50 INFO - Downloaded 59726181 bytes. 09:07:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:50 INFO - mkdir: /builds/slave/test/properties 09:07:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:50 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:50 INFO - Contents: 09:07:50 INFO - build_url:https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:07:50 INFO - Running post-action listener: _resource_record_post_action 09:07:50 INFO - Running post-action listener: set_extra_try_arguments 09:07:50 INFO - [mozharness: 2016-12-18 17:07:50.977894Z] Finished download-and-extract step (success) 09:07:50 INFO - [mozharness: 2016-12-18 17:07:50.978123Z] Running populate-webroot step. 09:07:50 INFO - Running pre-action listener: _resource_record_pre_action 09:07:50 INFO - Running main action method: populate_webroot 09:07:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:50 INFO - '--tppagecycles', 09:07:50 INFO - '7'], 09:07:50 INFO - 'tests': ['tsvgm']}}, 09:07:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['damp', 'tps']}, 09:07:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'tests': ['damp', 'tps']}, 09:07:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['dromaeo_dom']}, 09:07:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['basic_compositor_video']}, 09:07:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['a11yr', 09:07:50 INFO - 'ts_paint', 09:07:50 INFO - 'tpaint', 09:07:50 INFO - 'sessionrestore', 09:07:50 INFO - 'sessionrestore_no_auto_restore', 09:07:50 INFO - 'tabpaint']}, 09:07:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['tsvgx', 09:07:50 INFO - 'tsvgr_opacity', 09:07:50 INFO - 'tart', 09:07:50 INFO - 'tscrollx', 09:07:50 INFO - 'cart', 09:07:50 INFO - 'tsvg_static']}, 09:07:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:50 INFO - 'tsvgr_opacity', 09:07:50 INFO - 'tart', 09:07:50 INFO - 'tscrollx', 09:07:50 INFO - 'cart', 09:07:50 INFO - 'tsvg_static']}, 09:07:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'talos_options': ['--disable-e10s'], 09:07:50 INFO - 'tests': ['tp5o']}, 09:07:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'talos_options': ['--disable-e10s', 09:07:50 INFO - '--xperf_path', 09:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:50 INFO - 'tests': ['tp5n']}, 09:07:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:50 INFO - 'talos_options': ['--xperf_path', 09:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:50 INFO - 'tests': ['tp5n']}}, 09:07:50 INFO - 'talos.zip': {'path': '', 09:07:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:50 INFO - Running post-action listener: _resource_record_post_action 09:07:50 INFO - [mozharness: 2016-12-18 17:07:50.999916Z] Finished populate-webroot step (success) 09:07:51 INFO - [mozharness: 2016-12-18 17:07:51.000016Z] Running create-virtualenv step. 09:07:51 INFO - Running pre-action listener: _resource_record_pre_action 09:07:51 INFO - Running main action method: create_virtualenv 09:07:51 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:51 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 09:07:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:51 INFO - Using real prefix '/usr' 09:07:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:51 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:53 INFO - Installing pip.................done. 09:07:53 INFO - Return code: 0 09:07:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:07:53 INFO - Reading from file tmpfile_stdout 09:07:53 INFO - Output received: 09:07:53 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) 09:07:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:53 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:07:53 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 09:07:53 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 09:07:53 INFO - Using env: {'DISPLAY': ':0', 09:07:53 INFO - 'HOME': '/home/cltbld', 09:07:53 INFO - 'LANG': 'en_US.UTF-8', 09:07:53 INFO - 'LANGUAGE': 'en_US:en', 09:07:53 INFO - 'LOGNAME': 'cltbld', 09:07:53 INFO - 'MAIL': '/var/mail/cltbld', 09:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:53 INFO - 'MOZ_NO_REMOTE': '1', 09:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:53 INFO - 'NO_EM_RESTART': '1', 09:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:53 INFO - 'PWD': '/builds/slave/test', 09:07:53 INFO - 'SHELL': '/bin/bash', 09:07:53 INFO - 'SHLVL': '1', 09:07:53 INFO - 'TERM': 'linux', 09:07:53 INFO - 'TMOUT': '86400', 09:07:53 INFO - 'USER': 'cltbld', 09:07:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:53 INFO - '_': '/tools/buildbot/bin/python'} 09:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:54 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 09:07:54 INFO - Cleaning up... 09:07:54 INFO - Return code: 0 09:07:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07:54 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')]} 09:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:54 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')]} 09:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:54 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:07:54 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 09:07:54 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 09:07:54 INFO - Using env: {'DISPLAY': ':0', 09:07:54 INFO - 'HOME': '/home/cltbld', 09:07:54 INFO - 'LANG': 'en_US.UTF-8', 09:07:54 INFO - 'LANGUAGE': 'en_US:en', 09:07:54 INFO - 'LOGNAME': 'cltbld', 09:07:54 INFO - 'MAIL': '/var/mail/cltbld', 09:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:54 INFO - 'MOZ_NO_REMOTE': '1', 09:07:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:54 INFO - 'NO_EM_RESTART': '1', 09:07:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:54 INFO - 'PWD': '/builds/slave/test', 09:07:54 INFO - 'SHELL': '/bin/bash', 09:07:54 INFO - 'SHLVL': '1', 09:07:54 INFO - 'TERM': 'linux', 09:07:54 INFO - 'TMOUT': '86400', 09:07:54 INFO - 'USER': 'cltbld', 09:07:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:54 INFO - '_': '/tools/buildbot/bin/python'} 09:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:54 INFO - Downloading/unpacking psutil>=3.1.1 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:54 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 09:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:56 INFO - Installing collected packages: psutil 09:07:56 INFO - Running setup.py install for psutil 09:07:56 INFO - building 'psutil._psutil_linux' extension 09:07:56 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 09:07:56 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 09:07:56 INFO - building 'psutil._psutil_posix' extension 09:07:56 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 09:07:56 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 09:07:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:56 INFO - Successfully installed psutil 09:07:56 INFO - Cleaning up... 09:07:56 INFO - Return code: 0 09:07:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:56 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:07:56 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 09:07:56 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 09:07:56 INFO - Using env: {'DISPLAY': ':0', 09:07:56 INFO - 'HOME': '/home/cltbld', 09:07:56 INFO - 'LANG': 'en_US.UTF-8', 09:07:56 INFO - 'LANGUAGE': 'en_US:en', 09:07:56 INFO - 'LOGNAME': 'cltbld', 09:07:56 INFO - 'MAIL': '/var/mail/cltbld', 09:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:56 INFO - 'MOZ_NO_REMOTE': '1', 09:07:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:56 INFO - 'NO_EM_RESTART': '1', 09:07:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:56 INFO - 'PWD': '/builds/slave/test', 09:07:56 INFO - 'SHELL': '/bin/bash', 09:07:56 INFO - 'SHLVL': '1', 09:07:56 INFO - 'TERM': 'linux', 09:07:56 INFO - 'TMOUT': '86400', 09:07:56 INFO - 'USER': 'cltbld', 09:07:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:56 INFO - '_': '/tools/buildbot/bin/python'} 09:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:57 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 09:07:57 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 09:07:57 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 09:07:57 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 09:07:57 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 09:07:57 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 09:07:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:59 INFO - Installing collected packages: mozsystemmonitor 09:07:59 INFO - Running setup.py install for mozsystemmonitor 09:07:59 INFO - Successfully installed mozsystemmonitor 09:07:59 INFO - Cleaning up... 09:07:59 INFO - Return code: 0 09:07:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:07:59 INFO - Using env: {'DISPLAY': ':0', 09:07:59 INFO - 'HOME': '/home/cltbld', 09:07:59 INFO - 'LANG': 'en_US.UTF-8', 09:07:59 INFO - 'LANGUAGE': 'en_US:en', 09:07:59 INFO - 'LOGNAME': 'cltbld', 09:07:59 INFO - 'MAIL': '/var/mail/cltbld', 09:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:59 INFO - 'MOZ_NO_REMOTE': '1', 09:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:59 INFO - 'NO_EM_RESTART': '1', 09:07:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:59 INFO - 'PWD': '/builds/slave/test', 09:07:59 INFO - 'SHELL': '/bin/bash', 09:07:59 INFO - 'SHLVL': '1', 09:07:59 INFO - 'TERM': 'linux', 09:07:59 INFO - 'TMOUT': '86400', 09:07:59 INFO - 'USER': 'cltbld', 09:07:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:59 INFO - '_': '/tools/buildbot/bin/python'} 09:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:59 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:08:01 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 09:08:01 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 09:08:01 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 09:08:01 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 09:08:01 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 09:08:01 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 09:08:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:01 INFO - warning: no files found matching '*.txt' 09:08:01 INFO - no previously-included directories found matching 'build' 09:08:01 INFO - no previously-included directories found matching 'dist' 09:08:01 INFO - no previously-included directories found matching '.git*' 09:08:01 INFO - Installing collected packages: jsonschema, functools32 09:08:01 INFO - Running setup.py install for functools32 09:08:02 INFO - warning: no files found matching '*.txt' 09:08:02 INFO - no previously-included directories found matching 'build' 09:08:02 INFO - no previously-included directories found matching 'dist' 09:08:02 INFO - no previously-included directories found matching '.git*' 09:08:02 INFO - Successfully installed jsonschema functools32 09:08:02 INFO - Cleaning up... 09:08:02 INFO - Return code: 0 09:08:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:08:02 INFO - Using env: {'DISPLAY': ':0', 09:08:02 INFO - 'HOME': '/home/cltbld', 09:08:02 INFO - 'LANG': 'en_US.UTF-8', 09:08:02 INFO - 'LANGUAGE': 'en_US:en', 09:08:02 INFO - 'LOGNAME': 'cltbld', 09:08:02 INFO - 'MAIL': '/var/mail/cltbld', 09:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:02 INFO - 'MOZ_NO_REMOTE': '1', 09:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:02 INFO - 'NO_EM_RESTART': '1', 09:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:02 INFO - 'PWD': '/builds/slave/test', 09:08:02 INFO - 'SHELL': '/bin/bash', 09:08:02 INFO - 'SHLVL': '1', 09:08:02 INFO - 'TERM': 'linux', 09:08:02 INFO - 'TMOUT': '86400', 09:08:02 INFO - 'USER': 'cltbld', 09:08:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:02 INFO - '_': '/tools/buildbot/bin/python'} 09:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:08:02 INFO - Cleaning up... 09:08:02 INFO - Return code: 0 09:08:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:08:02 INFO - Using env: {'DISPLAY': ':0', 09:08:02 INFO - 'HOME': '/home/cltbld', 09:08:02 INFO - 'LANG': 'en_US.UTF-8', 09:08:02 INFO - 'LANGUAGE': 'en_US:en', 09:08:02 INFO - 'LOGNAME': 'cltbld', 09:08:02 INFO - 'MAIL': '/var/mail/cltbld', 09:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:02 INFO - 'MOZ_NO_REMOTE': '1', 09:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:02 INFO - 'NO_EM_RESTART': '1', 09:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:02 INFO - 'PWD': '/builds/slave/test', 09:08:02 INFO - 'SHELL': '/bin/bash', 09:08:02 INFO - 'SHLVL': '1', 09:08:02 INFO - 'TERM': 'linux', 09:08:02 INFO - 'TMOUT': '86400', 09:08:02 INFO - 'USER': 'cltbld', 09:08:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:02 INFO - '_': '/tools/buildbot/bin/python'} 09:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08:05 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 09:08:05 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 09:08:05 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 09:08:05 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 09:08:05 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 09:08:05 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 09:08:05 INFO - Downloading docopt-0.6.1.tar.gz 09:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:05 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:05 INFO - Running setup.py install for blobuploader 09:08:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:05 INFO - Running setup.py install for requests 09:08:05 INFO - Running setup.py install for docopt 09:08:05 INFO - Successfully installed blobuploader requests docopt 09:08:05 INFO - Cleaning up... 09:08:06 INFO - Return code: 0 09:08:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:06 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')]} 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:06 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')]} 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:06 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:06 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 09:08:06 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 09:08:06 INFO - Using env: {'DISPLAY': ':0', 09:08:06 INFO - 'HOME': '/home/cltbld', 09:08:06 INFO - 'LANG': 'en_US.UTF-8', 09:08:06 INFO - 'LANGUAGE': 'en_US:en', 09:08:06 INFO - 'LOGNAME': 'cltbld', 09:08:06 INFO - 'MAIL': '/var/mail/cltbld', 09:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:06 INFO - 'MOZ_NO_REMOTE': '1', 09:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:06 INFO - 'NO_EM_RESTART': '1', 09:08:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:06 INFO - 'PWD': '/builds/slave/test', 09:08:06 INFO - 'SHELL': '/bin/bash', 09:08:06 INFO - 'SHLVL': '1', 09:08:06 INFO - 'TERM': 'linux', 09:08:06 INFO - 'TMOUT': '86400', 09:08:06 INFO - 'USER': 'cltbld', 09:08:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:06 INFO - '_': '/tools/buildbot/bin/python'} 09:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:06 INFO - Running setup.py (path:/tmp/pip-Y9GMIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:06 INFO - Running setup.py (path:/tmp/pip-vABPPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:06 INFO - Running setup.py (path:/tmp/pip-_QFM3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:06 INFO - Running setup.py (path:/tmp/pip-RqZp8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:06 INFO - Running setup.py (path:/tmp/pip-DxM7l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:06 INFO - Running setup.py (path:/tmp/pip-JNaziP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:06 INFO - Running setup.py (path:/tmp/pip-wOKetv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:06 INFO - Running setup.py (path:/tmp/pip-YHLboE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:06 INFO - Running setup.py (path:/tmp/pip-7SHhJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:06 INFO - Running setup.py (path:/tmp/pip-LHNnA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:07 INFO - Running setup.py (path:/tmp/pip-OCXN_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:07 INFO - Running setup.py (path:/tmp/pip-cJ3KFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:07 INFO - Running setup.py (path:/tmp/pip-oi8De7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:07 INFO - Running setup.py (path:/tmp/pip-eL4jwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:07 INFO - Running setup.py (path:/tmp/pip-JAzEf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:07 INFO - Running setup.py (path:/tmp/pip-IoTbB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:07 INFO - Running setup.py (path:/tmp/pip-DF1jxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:07 INFO - Running setup.py install for manifestparser 09:08:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:07 INFO - Running setup.py install for mozcrash 09:08:07 INFO - Running setup.py install for mozdebug 09:08:07 INFO - Running setup.py install for mozdevice 09:08:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:07 INFO - Running setup.py install for mozfile 09:08:08 INFO - Running setup.py install for mozhttpd 09:08:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozinfo 09:08:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozInstall 09:08:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozleak 09:08:08 INFO - Running setup.py install for mozlog 09:08:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for moznetwork 09:08:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozprocess 09:08:08 INFO - Running setup.py install for mozprofile 09:08:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozrunner 09:08:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:08 INFO - Running setup.py install for mozscreenshot 09:08:09 INFO - Running setup.py install for moztest 09:08:09 INFO - Running setup.py install for mozversion 09:08:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:09 INFO - Cleaning up... 09:08:09 INFO - Return code: 0 09:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:09 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')]} 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:09 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')]} 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:09 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:09 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 09:08:09 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 09:08:09 INFO - Using env: {'DISPLAY': ':0', 09:08:09 INFO - 'HOME': '/home/cltbld', 09:08:09 INFO - 'LANG': 'en_US.UTF-8', 09:08:09 INFO - 'LANGUAGE': 'en_US:en', 09:08:09 INFO - 'LOGNAME': 'cltbld', 09:08:09 INFO - 'MAIL': '/var/mail/cltbld', 09:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:09 INFO - 'MOZ_NO_REMOTE': '1', 09:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:09 INFO - 'NO_EM_RESTART': '1', 09:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:09 INFO - 'PWD': '/builds/slave/test', 09:08:09 INFO - 'SHELL': '/bin/bash', 09:08:09 INFO - 'SHLVL': '1', 09:08:09 INFO - 'TERM': 'linux', 09:08:09 INFO - 'TMOUT': '86400', 09:08:09 INFO - 'USER': 'cltbld', 09:08:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:09 INFO - '_': '/tools/buildbot/bin/python'} 09:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:09 INFO - Running setup.py (path:/tmp/pip-nkRlCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:09 INFO - Running setup.py (path:/tmp/pip-cbCRNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:09 INFO - Running setup.py (path:/tmp/pip-cU0gP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:09 INFO - Running setup.py (path:/tmp/pip-YvRKOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:09 INFO - Running setup.py (path:/tmp/pip-Mhp23r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:09 INFO - Running setup.py (path:/tmp/pip-UrIdBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:09 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)) 09:08:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:09 INFO - Running setup.py (path:/tmp/pip-V3FKmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:10 INFO - Running setup.py (path:/tmp/pip-sEwV_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:10 INFO - Running setup.py (path:/tmp/pip-CLZl3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:10 INFO - Running setup.py (path:/tmp/pip-RKm2lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:10 INFO - Running setup.py (path:/tmp/pip-ubLojv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:10 INFO - Running setup.py (path:/tmp/pip-k8Kibm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:10 INFO - Running setup.py (path:/tmp/pip-4uqCS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:10 INFO - Running setup.py (path:/tmp/pip-8nxaHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:10 INFO - Running setup.py (path:/tmp/pip-dLy7gA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:10 INFO - Running setup.py (path:/tmp/pip-CCBWex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:10 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)) 09:08:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:10 INFO - Running setup.py (path:/tmp/pip-Vi7FDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:10 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)) 09:08:10 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)) 09:08:10 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)) 09:08:10 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)) 09:08:10 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)) 09:08:10 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)) 09:08:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:12 INFO - Downloading blessings-1.6.tar.gz 09:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:12 INFO - Installing collected packages: blessings 09:08:12 INFO - Running setup.py install for blessings 09:08:12 INFO - Successfully installed blessings 09:08:12 INFO - Cleaning up... 09:08:13 INFO - Return code: 0 09:08:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:13 INFO - Reading from file tmpfile_stdout 09:08:13 INFO - Current package versions: 09:08:13 INFO - argparse == 1.2.1 09:08:13 INFO - blessings == 1.6 09:08:13 INFO - blobuploader == 1.2.4 09:08:13 INFO - docopt == 0.6.1 09:08:13 INFO - functools32 == 3.2.3-2 09:08:13 INFO - jsonschema == 2.5.1 09:08:13 INFO - manifestparser == 1.1 09:08:13 INFO - mozInstall == 1.12 09:08:13 INFO - mozcrash == 1.0 09:08:13 INFO - mozdebug == 0.1 09:08:13 INFO - mozdevice == 0.49 09:08:13 INFO - mozfile == 1.2 09:08:13 INFO - mozhttpd == 0.7 09:08:13 INFO - mozinfo == 0.9 09:08:13 INFO - mozleak == 0.1 09:08:13 INFO - mozlog == 3.4 09:08:13 INFO - moznetwork == 0.27 09:08:13 INFO - mozprocess == 0.23 09:08:13 INFO - mozprofile == 0.28 09:08:13 INFO - mozrunner == 6.13 09:08:13 INFO - mozscreenshot == 0.1 09:08:13 INFO - mozsystemmonitor == 0.3 09:08:13 INFO - moztest == 0.7 09:08:13 INFO - mozversion == 1.4 09:08:13 INFO - psutil == 3.1.1 09:08:13 INFO - requests == 1.2.3 09:08:13 INFO - wsgiref == 0.1.2 09:08:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:13 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')]} 09:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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')]} 09:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:13 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 09:08:13 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 09:08:13 INFO - Using env: {'DISPLAY': ':0', 09:08:13 INFO - 'HOME': '/home/cltbld', 09:08:13 INFO - 'LANG': 'en_US.UTF-8', 09:08:13 INFO - 'LANGUAGE': 'en_US:en', 09:08:13 INFO - 'LOGNAME': 'cltbld', 09:08:13 INFO - 'MAIL': '/var/mail/cltbld', 09:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:13 INFO - 'MOZ_NO_REMOTE': '1', 09:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:13 INFO - 'NO_EM_RESTART': '1', 09:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:13 INFO - 'PWD': '/builds/slave/test', 09:08:13 INFO - 'SHELL': '/bin/bash', 09:08:13 INFO - 'SHLVL': '1', 09:08:13 INFO - 'TERM': 'linux', 09:08:13 INFO - 'TMOUT': '86400', 09:08:13 INFO - 'USER': 'cltbld', 09:08:13 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:13 INFO - '_': '/tools/buildbot/bin/python'} 09:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 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)) 09:08:13 INFO - Cleaning up... 09:08:13 INFO - Return code: 0 09:08:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:13 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')]} 09:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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')]} 09:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:13 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 0x13931f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x155fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, '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': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', '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 09:08:13 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 09:08:13 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 09:08:13 INFO - Using env: {'DISPLAY': ':0', 09:08:13 INFO - 'HOME': '/home/cltbld', 09:08:13 INFO - 'LANG': 'en_US.UTF-8', 09:08:13 INFO - 'LANGUAGE': 'en_US:en', 09:08:13 INFO - 'LOGNAME': 'cltbld', 09:08:13 INFO - 'MAIL': '/var/mail/cltbld', 09:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:13 INFO - 'MOZ_NO_REMOTE': '1', 09:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:13 INFO - 'NO_EM_RESTART': '1', 09:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:13 INFO - 'PWD': '/builds/slave/test', 09:08:13 INFO - 'SHELL': '/bin/bash', 09:08:13 INFO - 'SHLVL': '1', 09:08:13 INFO - 'TERM': 'linux', 09:08:13 INFO - 'TMOUT': '86400', 09:08:13 INFO - 'USER': 'cltbld', 09:08:13 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:13 INFO - '_': '/tools/buildbot/bin/python'} 09:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:08:13 INFO - Cleaning up... 09:08:13 INFO - Return code: 0 09:08:13 INFO - Running post-action listener: _resource_record_post_action 09:08:13 INFO - Running post-action listener: _start_resource_monitoring 09:08:13 INFO - Starting resource monitoring. 09:08:13 INFO - [mozharness: 2016-12-18 17:08:13.794063Z] Finished create-virtualenv step (success) 09:08:13 INFO - [mozharness: 2016-12-18 17:08:13.794589Z] Running install step. 09:08:13 INFO - Running pre-action listener: _resource_record_pre_action 09:08:13 INFO - Running main action method: install 09:08:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:14 INFO - Reading from file tmpfile_stdout 09:08:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:14 INFO - Reading from file tmpfile_stdout 09:08:14 INFO - Output received: 09:08:14 INFO - Usage: mozinstall [options] installer 09:08:14 INFO - Options: 09:08:14 INFO - -h, --help show this help message and exit 09:08:14 INFO - -d DEST, --destination=DEST 09:08:14 INFO - Directory to install application into. [default: 09:08:14 INFO - "/builds/slave/test"] 09:08:14 INFO - --app=APP Application being installed. [default: firefox] 09:08:14 INFO - mkdir: /builds/slave/test/build/application 09:08:14 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'] 09:08:14 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 09:08:31 INFO - Reading from file tmpfile_stdout 09:08:31 INFO - Output received: 09:08:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:08:31 INFO - Running post-action listener: _resource_record_post_action 09:08:31 INFO - [mozharness: 2016-12-18 17:08:31.145485Z] Finished install step (success) 09:08:31 INFO - [mozharness: 2016-12-18 17:08:31.145731Z] Running run-tests step. 09:08:31 INFO - Running pre-action listener: _resource_record_pre_action 09:08:31 INFO - Running main action method: run_tests 09:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:31 INFO - Python 2.7.3 09:08:31 INFO - Return code: 0 09:08:31 INFO - grabbing minidump binary from tooltool 09:08: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')]} 09:08:31 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 0x17023f0>, '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 0x1702cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17031a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:31 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 09:08:31 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 09:08:31 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 09:08:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:31 INFO - Return code: 0 09:08:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:08:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:08:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 09:08:31 INFO - Using env: {'DISPLAY': ':0', 09:08:31 INFO - 'HOME': '/home/cltbld', 09:08:31 INFO - 'LANG': 'en_US.UTF-8', 09:08:31 INFO - 'LANGUAGE': 'en_US:en', 09:08:31 INFO - 'LOGNAME': 'cltbld', 09:08:31 INFO - 'MAIL': '/var/mail/cltbld', 09:08:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:31 INFO - 'MOZ_NO_REMOTE': '1', 09:08:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:31 INFO - 'NO_EM_RESTART': '1', 09:08:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:31 INFO - 'PWD': '/builds/slave/test', 09:08:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:31 INFO - 'SHELL': '/bin/bash', 09:08:31 INFO - 'SHLVL': '1', 09:08:31 INFO - 'TERM': 'linux', 09:08:31 INFO - 'TMOUT': '86400', 09:08:31 INFO - 'USER': 'cltbld', 09:08:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141', 09:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:31 INFO - '_': '/tools/buildbot/bin/python'} 09:08:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:08:31 INFO - mozversion application_buildid: 20161218073118 09:08:31 INFO - mozversion application_changeset: 2824deb82146bba070995a762cfc98ddb2d1decb 09:08:31 INFO - mozversion application_display_name: Nightly 09:08:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:31 INFO - mozversion application_name: Firefox 09:08:31 INFO - mozversion application_remotingname: firefox 09:08:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:08:31 INFO - mozversion application_vendor: Mozilla 09:08:31 INFO - mozversion application_version: 53.0a1 09:08:31 INFO - mozversion platform_buildid: 20161218073118 09:08:31 INFO - mozversion platform_changeset: 2824deb82146bba070995a762cfc98ddb2d1decb 09:08:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:08:31 INFO - mozversion platform_version: 53.0a1 09:08:31 INFO - using testdate: 1482080911 09:08:31 INFO - actual date: 1482080911 09:08:31 INFO - starting webserver on 'localhost:52679' 09:08:31 INFO - SUITE-START | Running 2 tests 09:08:31 INFO - TEST-START | dromaeo_css 09:08:31 INFO - operating with platform_type : linux_ 09:08:31 INFO - Initialising browser for dromaeo_css test... 09:08:31 INFO - TEST-INFO | started process 15810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4QX79o/profile http://localhost:52679/getInfo.html) 09:08:36 INFO - PROCESS | 15810 | __metrics Screen width/height:1600/1200 09:08:36 INFO - PROCESS | 15810 | colorDepth:24 09:08:36 INFO - PROCESS | 15810 | Browser inner width/height: 1024/697 09:08:36 INFO - PROCESS | 15810 | __metrics 09:08:37 INFO - PROCESS | 15810 | 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" 09:08:37 INFO - PROCESS | 15810 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:37 INFO - PROCESS | 15810 | console.error: PushService: 09:08:37 INFO - PROCESS | 15810 | stateChangeProcessEnqueue: Error transitioning state 09:08:37 INFO - PROCESS | 15810 | UnknownError 09:08:38 INFO - TEST-INFO | 15810: exit 0 09:08:38 INFO - Browser initialized. 09:08:38 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:38 INFO - TEST-INFO | started process 15895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4QX79o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:50 INFO - PROCESS | 15895 | RSS: Main: 154238976 09:08:50 INFO - PROCESS | 15895 | 09:09:41 INFO - PROCESS | 15895 | 1482080981479 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] 09:11:52 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52679/tests/dromaeo/cssquery-ext.html) 09:11:53 INFO - PROCESS | 15895 | RSS: Main: 256507904 09:11:53 INFO - PROCESS | 15895 | 09:14:55 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-ext.html (next: http://localhost:52679/tests/dromaeo/cssquery-jquery.html) 09:14:56 INFO - PROCESS | 15895 | RSS: Main: 244391936 09:14:56 INFO - PROCESS | 15895 | 09:17:58 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52679/tests/dromaeo/cssquery-mootools.html) 09:17:59 INFO - PROCESS | 15895 | RSS: Main: 223821824 09:17:59 INFO - PROCESS | 15895 | 09:21:01 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52679/tests/dromaeo/cssquery-prototype.html) 09:21:02 INFO - PROCESS | 15895 | RSS: Main: 188825600 09:21:02 INFO - PROCESS | 15895 | 09:24:04 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52679/tests/dromaeo/cssquery-yui.html) 09:24:05 INFO - PROCESS | 15895 | RSS: Main: 188821504 09:24:05 INFO - PROCESS | 15895 | 09:27:08 INFO - PROCESS | 15895 | Cycle 1(1): loaded http://localhost:52679/tests/dromaeo/cssquery-yui.html (next: http://localhost:52679/tests/dromaeo/cssquery-dojo.html) 09:27:09 INFO - PROCESS | 15895 | RSS: Main: 282406912 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | __start_tp_report 09:27:09 INFO - PROCESS | 15895 | _x_x_mozilla_page_load 09:27:09 INFO - PROCESS | 15895 | _x_x_mozilla_page_load_details 09:27:09 INFO - PROCESS | 15895 | |i|pagename|runs| 09:27:09 INFO - PROCESS | 15895 | |0;dojo.html;3006.639203295604;3045.9847700761507;3050.6491753448345;3077.522983937488;3086.2130156810026;5070.29015937769;5107.208382700683;5130.230465430178;5150.330457040584;5169.3021442105255;5142.794288228476;5201.635885488017;5216.400113986892;5219.347581552306;5270.341207349082;4708.90582188356;4719.315699223611;4754.78603462845;4762.78567464465;4792.976035119819;4773.6897101688455;4843.346148269974;4845.321654968289;4852.781624826891;4892.290617860408;4643.558861908147;4778.95221047792;4784.784684689197;4786.2581299898575;4889.168841296971;4664.020555683311;4664.883377915546;4713.457952335491;4728.66899317048;4731.455882573479;4665.743384113873;4684.086603112394;4684.765761711935;4738.3129446230105;4743.217369134089;5178.870528236786;5186.40356359016;5194.896102077975;5196.896062078775;5200.037992971274;5415;5446.537044351235;5458.727063646802;5538.280023596947;5548.611597188198;5537.252470916415;5541.113421852484;5586.441355864422;5614.606977511576;5618.438156184389;5207.791688332462;5209.713465759344;5214.556762675177;5219.504147105981;5266.578673706094;5483.287172667608;5512.4211957744665;5591.30108736408;5658.773649053992;5666.546676265889;5446.319210098738;5460.453954604508;5462.3445186577865;5516.696581687966;5517.779288828402;5490.423505531941;5535.142052981794;5537.169424586345;5541.612087153861;5545.584081193927;5352.678839269578;5395.21769343443;5528.474794894479;5529.834104976857;5533.059379905426;5413.052715774724;5429.375621803543;5444.3194600674915;5455.727213639302;5460.290162278879;4208.705390622688;4215.557366476476;4216.831326746896;4217.641500472494;4261;3789.7347185696735;3793.7154713396053;3805.8858234253016;3828.196078823423;3848.2688289224957;5223.5559977402345;5256.448072952285;5260.710660913686;5274.12976858814;5297.947020529746;5529.695866727368;5537.58468114893;5541.168824676331;5541.418151094077;5541.501264886179;5130.153524668388;5252.842414727565;5266.315379000782;5270.973645131749;5316.175992721134;4706.647001474903;4732.810687572527;4787.3537072495465;4787.497312782178;4788.066327066258;5672.7730890763905;5675.2054712340305;5683.26117604717;5683.687397193194;5687.289088863975;5489.945100548944;5508.697021663847;5511.145772405283;5511.559075274049;5514.531264842373;5652.434756524314;5682.659040457585;5695.430456954271;5698.572607054404;5704.1443783432815;5442;5452.91820622683;5453.182022696627;5455.3453585568395;5458.1266997280245;5544.112941929272;5544.528715059265;5545.861353466195;5546.889062218814;5548.056830338772;5297.311349524537;5301.734913254399;5313.070212712682;5358.437364076872;5362.222477740784;5348.866278343073;5350.304460420276;5352.625316227826;5370.785168593213;5398.271233383443;5255.474452554714;5259.368875734936;5266.34170728659;5285.260063591023;5314.335708036496;5395.136778115482;5395.919061214006;5399.2980912481125;5438.646487978269;5439.129739241703;5285.127953887565;5289.973550132225;5293.073712100444;5296.470352964827;5339.839804805862 09:27:09 INFO - PROCESS | 15895 | |1;ext.html;532.9014132385507;549.6125231711641;565.5786439102869;589.6933594530844;590.8552404660859;31383.372332553365;34266.31467370654;34320.828395857985;39462.408063879;41126;42400.36399454011;42878;42904.356434653506;42928.99999999992;42966.570334296644;8553.529555874424;8739.213470787628;8742.86885696715;8744.081871403507;8744.213020828125;7617.124030736453;7773.883391749128;8540.274076703488;8653.740387788344;9979.401235925867;9935.751606209866;10302.227332950059;10302.433366164858;10302.48487575622;10303.63937262196;10234.744131396737;10277.434741089237;10283.948580257125;10287.537060832243;10292.279540432173;9972.102510773993;9978.800423991552;9998.150157236643;10070.748231294203;10083.092521673085;40506.79746601277;40509.189816203805;40534.797326013184;40596;40652.59347406518;3130.20179854138;3141.1204862638497;3184.471453702236;3219.983900080508;3466.757326987112;30439.391212175855;30833.69166308353;31507.36985260305;31571.10796775109;31635.20911977219;3660.7437479376454;3744.1762812181278;3775.6601905828607;3780.206156707095;3818.236352729465;321675;322258;323236;323718;324597;54177.56200970887;54315;54431.72784136133;54462.45537544653;54632.453675463534;57585.712071439375;57802.7109864448;57817.42182578205;57837.710811446515;57857;15596.532104036896;15783.68432631364;15821.525354239391;15829.050256984618;15843.683126337408;11546.59586914454;11580.88419115815;11582.131340149523;11584.826227606593;11621.76756464883;13526.59420217395;13826.65433364174;15377.154256515776;19069.332573359865;19569.706454403196;6433.356664333529;6512.381323774234;6520.641364724891;6662.70077334915;6906.827329316707;888.7778055486128;965.2471072563412;998.4857798233916;1024.5235965276183;1029.6550655530332;31076.844615776776;31126.68873311283;31172.53241201384;31385.686143138733;31940.84029579837;1008.4705529596873;1040.6409788622914;1078.4338222433128;1091.645215305029;1134.5405110930087;15807.215018693929;16246.75629865553;16286;16330.856840021084;16462.75305870413;523064;523191;523277;524176.1318189148;525257;48977.51022489801;49796.50203497991;49944.50055499398;54040.45959540433;56802;1293.780057390227;1329.7805862032656;1333.973320533584;1334.9332533373292;1336.4119787293755;28858;30161.849190753906;30255.69744302617;30765.846170769004;30870.923366491243;48983.26525102055;49299.82351853004;49427.258591120444;49463.50536494589;49504.2574361392;433085;666728;679461;684806;690723;36807.44788828116;36812;36839.263214735016;37076.44385334168;37363.813180933925;41050;41114.79442602768;41135.7943210282;41250.58749412587;42097.579024210594;35341.29317413689;35600;35804.82097589495;35955.82022089873;36238.45642315315;35320;35510.82244588864;35632.28735425329;35732.82133589315;35982.82008590045;34849;35507;35518.822405887804;35578.8221058893;36327.81836090803;30100.698993009788;33031.33937321192;33032.33935321328;33048.50427243641;33110.17224569405;24774.25677229686;24934.251972440856;25032.374190644652;25565.488690226462;25614.743852561234 09:27:09 INFO - PROCESS | 15895 | |2;jquery.html;356.7039357333413;370.66006386911323;379.4554813842136;383.67196047379497;402.589358853969;14087.295635218237;14558.56324310271;18628.441146765585;18658.160382782775;18672.626547469077;19437.70843437346;20105.497362565944;20234.89882550585;20334;20395.08222130004;4527.841525546606;5869.9706501467435;6022.608530445529;6092.878142437148;6095.664738439384;6445.645489498076;7089;7119.50163488556;7230.638468076601;7235.963820180892;7061.399781018619;7094.219635840053;7105.538140020908;7158.033665455175;7193.676284567181;6944.992976018449;7043.718251269943;7137.464690148234;7179.210286868441;7180.748673796418;6843.8973415398805;6884.552504087218;6913.1358580177475;6917.9308206918295;6918.169819621678;24500.632490512657;24920;25079.874600627063;25237.747622523722;25526.489470210676;2351.341624345186;2382.702162229721;2576.6779152605923;2717.184844546628;2820.732030457103;14798.778018329735;14889.70220595582;15481.458148964732;15768.92115539415;15812.36750529989;2011.265887950902;2062.876227426344;2066.2664754012258;2070.1615845582564;2116.6295898217904;88075;88835.55582222047;90535;91095.63356549724;92017;16890.324387024382;16982.830171698126;17090.572735681708;17359.739603905953;17382;29637.85181074081;29907.551386729217;30038.849805750833;30150.246243844078;30259.69740302613;8986.595603197871;9296.628134874665;9384.296177786557;9534.85030889418;9596;7401.629918504053;7550.131734850453;7853.0943550885395;8057.959710201529;8078.636461359135;8328.583570821434;8574.399792014623;8666.393352465393;8680.826383472422;8820.898282298762;3082.799618024821;3342.2479942013338;3365.495175723612;3397.2016576104597;3461.3769521486374;914.3234006834895;926.3006430145198;932.6595792535676;938.8028514012134;1040.5941682743735;17610.911945440446;17992.640147196984;18533.258669653063;18819.786258933065;18888.150033248538;925.1488630459993;958.5734348214977;973.571628483465;993.3592832622918;995.0994350113159;9815.656452024145;10312.43281619503;10435.32170408921;10460.215483838743;10482.266241363184;405467;410131.392116474;419502;419982;421123;82620.58689706514;83100.16899831046;84608;84948;85637;948.62055177928;954.2795189631784;979.5836769372988;981.215027977615;981.9901800982042;15728.685426291639;15956.281967311732;16037.599060023593;16598.17009149562;16958.660826783642;35229;38854.41718374283;40348.394774077824;40949.79525102475;41807;51867.740661296455;53578.464215357344;53673.731631341594;55065.72467137639;56281.71859140678;7941.563214023284;8019.318357939641;8321.583920804056;8327.541985190872;8430.367722420966;11728.41357932117;12689.619311420673;12766;12813.103082784115;12868.22790632565;11030.613928512463;11245.493952772309;11501.712457188638;11535.250208736405;11546.653600392001;10186.236032297755;10671.35971841692;10927.398993055458;11425.54403782542;11633.47649355764;11539.769204615694;11725.589604363806;11761.176717630025;11774.705632359259;11886.465109070286;7973.242541958504;8193.67225310981;8318.334533237448;8327.50034997902;8432.620532075949;8249.257566819015;11414.48634811419;11448.656540303804;11508.194426390073;11533.538658453568 09:27:09 INFO - PROCESS | 15895 | |3;mootools.html;1297.007789041383;1388.9027768056235;1407.4933024111322;1520.399442220323;1701.7702610147635;6802.353776391241;7531.811704707385;7850.76447706569;7850.803729906741;8019.318357939582;7817.374610031198;7890.92109078909;7899.723509677148;7903.6048197590035;7905.960470197641;1872.1575291118984;2110.8627939183943;2143.7641859395453;2144.421006328298;2145.839062070343;1864.6363959027994;1872.7659042619673;1875.6998880179174;2120.0777661717125;2198.1866709317574;2051.3333166720877;2061.40219336393;2065.3494149342982;2067.514134178467;2071.6167509010806;2104.67377556479;2110.155937624947;2114.6616541353387;2119.925802596917;2149.215536329244;2107.6417009108336;2111.514351699118;2130.3502431758307;2132.4988627672483;2134.2316765964397;7560.319571238615;7738;7742.187070357644;7749.225077492262;7749.341305988998;4736.242201247818;4844.418669759616;4882.560569548722;4884.755762211911;4899.314096026524;2966.480865848468;3010.247438140465;3073.754099672043;3090.9381812363627;3092.6288845338704;951.8001219743793;970.1317320997738;978.2614126334572;985.6747273399761;1013.1945103642548;10627.681169564925;10749.731256718567;10852.728681782939;10882.782344353147;10911;4145.316022856256;4285.635720963753;4326.19965306413;4415.205263052617;4422;9044.547772611342;9050.909490905007;9126.13301736334;9131.086891310948;9138.223251023737;4867.415910090812;4902.50974902514;4907.1903135982875;4911.729854857946;4914.262860570871;4398.274284743045;4491.214037543416;4558.270676691714;4566.20091483989;4571.588557029883;3389.339078879644;3458.049036514928;3579.1231148368297;3601.3157500074904;3756.1924186300075;1168.5384273212035;1175.1480176871871;1181.9881801181878;1191.4876603060593;1201.1531870031606;727.0802434919831;744.7505085796319;745.6868115391549;868.4919322196586;920.0569416348296;5029.1450453423;5225.947740522623;5254.65844720092;5359.249705041298;5425.601526481177;911.3483859040817;924.3437159616612;925.5881132895797;928.767808047988;946.2619157057507;3492.9825350873084;3623.5289412376223;3642.453631955228;3654.9634503654624;3768.1333293342136;9223.400478968846;9582.616695332108;9665.806683866283;9694.36986595869;9721.562529686182;9256.583453744597;9311.90688093124;9427.717168484956;9440.669576564787;9442.055794420641;7044.999999999897;7123.145222573221;7218.133823941055;7279.854402911912;7292.744753933587;4834.758262086952;4874.78063487151;4886;5010.223415370623;5156.845294641167;8625.266852317623;8630.482171069756;8656.48061116335;8717.346199035224;8751.862257906432;8637.82724345497;8910.420822646507;8913.320412603929;9004.59479323419;9054.54727263621;6983.685734142077;7037.7184912602925;7091.2554181810165;7092.148942126978;7093.787186384417;6769.729210831511;7008.719651213894;7017.157941047107;7312.268773122645;7339.082614673166;6917.204521480095;6924.134483189601;7017.228104908558;7041.119860017498;7120.287971202838;6634.270230274569;6726.260111387841;6760;6762.695678694569;6817.5909445433435;6100.511959043355;6211.565190436807;6220.720067596879;6223.533235007483;6228.532860035425;4538.2738761797955;4550.681452298308;4571.47428045779;4617.492075871584;4661.440627124767;4413.757243351646;4421.690481666253;4511.571400716926;4621.60716339115;4712.7172369656555 09:27:09 INFO - PROCESS | 15895 | |4;prototype.html;361.6564263949247;364.937960546707;367.0566643725625;372.8024147201982;376.9830357633906;5115.437301896793;5205.401378841434;5210;5246.816361427351;5269.841904742859;4963.454020057799;4972.179590367585;5104.515071068252;5124.538791508763;5211.973940130303;4001.8199181036925;4115.382692596105;4129.4012368206595;4136.544980052192;4150.232207041692;4050.2911990401562;4058;4077.3476243800997;4086.3257562502154;4111.8972025699295;3901.239258344624;3957.7031722620773;4061.7562946223175;4073.3523209913646;4086.979565102185;4054.776987265699;4062.2376783787013;4080.3879418087226;4083.8774836754797;4093.4678491796176;3681.5766186888313;3685.060309621056;3687.7049836013052;3689;3691.630836916314;4830.3237546743485;5021.774020169101;5085.694858308513;5093.745312734385;5107.61692873036;4429.579189976947;4475;4488.259437192859;4491;4498.910021799546;5321.547668448186;5323.840284791462;5365.248865158881;5420.214068960018;5429.592780541475;3726.3851464508325;3755.4414917809077;3807.162424266657;3809.4095415210686;3815.5993620669983;5497.917531237034;5510.9448905510835;5531.3915469298345;5537.529310008654;5550.4727050930505;3923.509561304837;3925.803709814498;3929.646331830149;3941.4876066111224;3944.8422063117155;3940.6798722427734;3945.8421663133727;3968.6229808168177;4003.2994226010333;4026.99988508563;4491.483479399911;4542.409486766699;4542.705328981225;4562.452505699337;4583.945692490745;5353.304070470814;5359.410464848864;5373.543248823894;5373.731313434313;5374.140137578047;1332.4670131947298;1338.618493729302;1344.610063081715;1349.601867449105;1352.6821197018692;873.9737807865774;879.7800549862534;922.3904287019194;933.1508327422149;934.6822080492665;5034.89930201394;5035.1188542005;5062.4684408137355;5072.847814565569;5076.771545280471;5269.894602107936;5333.813316533902;5351.528329709283;5364.7317634118135;5365.54392876602;4824.638152138534;4965.32968049316;4999.600031997432;5051.848444546669;5068.290439338495;2790.074848354629;2809.578563215534;2821.3369858083333;2842.815217010888;2848.1882663441233;5436.456354364532;5439.646422982572;5457.508824205761;5484.862878427992;5489.533389661844;5313.654612450178;5632.211490391349;5641.041023026095;5641.182028605911;5658.236138121383;5712.057510510802;5733.082706766981;5735.598508104476;5742.7702891883855;5746.3966283541295;5302.893942121059;5484.369297530835;5584.497395234448;5586.273784408001;5591.664500130005;5496.340439147328;5512.228288039758;5512.4211957745465;5517.917231241454;5535.640183388067;5728.598998070095;5733.226014487991;5764.250647415809;5769.9711501442225;5775.5090817281;3988.4216788566077;4119.661110139157;4171.353440216772;4177.289860723624;4201.18076974987;5537.972310138423;5548.445155484419;5549.445055494418;5550.611457197957;5561.55183786039;5414.756335964812;5425.484578964992;5432.809851655173;5442.510174084351;5447.918281225706;5209.557187638941;5411.645934018715;5429.484199001088;5446.665566935968;5456.61803673739;5136.589072874086;5264.684118952875;5339.932013597374;5364.544013758874;5368.409474957673;5314.946850531445;5469.835904922858;5474.822913073708;5481.753321100639;5491.862703432446;5331.467552912336;5360.311501876897;5387.434319396407;5389.487998640123;5393.757280922447 09:27:09 INFO - PROCESS | 15895 | |5;yui.html;649.5031301054693;697.8743826111302;708.8015355700403;752.4893455023682;758.3667637522668;14399.136051836884;15195.772063419045;15910.84089159111;16193.271302791372;16202.108884011375;14038.368273427695;14320.498782542612;14384.280785960713;14444.783328250085;14484.13095214288;776.6932061835586;795.0935933036343;795.7692269241913;796.0765512006074;798.1061211443192;666.5001249063203;690.1132045321763;764.8776195808672;791.6674996501455;795.8965334506535;829.5022986208257;838.6771093129158;839.7228914458215;840.5881118252044;841.9873701894477;831.3806214370272;831.6049876308796;832.4797001873828;832.7834762961613;832.9750107496785;515.0368810324679;531.0573731626356;532.6271609873104;608.8203979825962;609.3723464831187;12804.231746095264;14780.482683106147;15204.16377099259;15320.387184512718;15786.368545258176;12890;12941.48234070636;12995.610131696065;13100;13443.327833608379;11449.542018319256;11501.367424791633;11616.709582260426;11686.824697629543;12175.82307865116;3439.94840077399;3460.6885380315894;3464.22055037617;3476.947845782315;3586.0855481852223;76622.69967583241;77420.22579774186;77816;79064.20935790626;80537.38925221522;10134.797304054026;10227.795444091076;10501.684949451528;10509.421981791074;10616.25686201974;19117.13972871224;19779.901100494404;19784.208631654572;20483.69274460884;20549;7424.294692004251;7514.361279291322;7643.235676432312;7744.535327880345;7854.72508462201;4325.675574331937;4393.0994146199955;4397.912041759147;4476.664250181249;4523.140603285366;3447.1382154461385;3502.492138639883;3519.2257703305745;3541.911452213664;3550.467429885538;590.7784580782206;596.272547492059;614.827848202504;616.0236025899001;617.8486270863577;533.2827347217985;553.3415235869383;632.5666918160982;642.8746394453037;654.6955665615417;8320.459170154041;9448.88959833978;9621.629710921994;9654.179394751385;9746.463944482979;670.1321788284183;678.0270312102149;692.6986760759086;701.638656092113;709.3296834491459;4328.220920234388;4572.862814115582;4624.213883639789;4633.142868569325;4777.928331075037;79102.20897791063;80581.19418805854;80881;81890.18109818826;83641;57716;68791.31208687849;70669.2933070673;71649.64175179091;72368.63815680888;60522.69738651367;62369.68815155986;63804.68097659482;66818.66590667015;67105.6644716783;8917.063708310534;8969.192772650493;9028.503432311287;9235.584398702209;9249.16757491829;45505.77247113743;45950;46441.76779116218;46563;47122.82192945204;45386.54613453955;49150.26274605812;51328.230076549626;52699;53152.46847531475;11905.50587488394;11922.105842062054;12040;12262.325572093621;13110.082294239312;14288.42846286137;14556.962025316523;14620.778512847533;14632.268386580841;14705.70588588201;12704.6823829405;12899.93550032244;13042.869571304165;13091.279979601213;13342.19946803195;11412.657620271406;11633.800704805908;11659.18385712992;12151.331676757864;12211.511539538318;11795.233309834834;12272.570460033856;12421.689457763629;12583.433745481658;12720;10701;11029.724256893322;11183.32900026001;11208.10335173168;11373.943130284295;10267.84598231042;10718.785624287315;10769.223078192324;10901.727456813644;10993.725156871142 09:27:09 INFO - PROCESS | 15895 | __end_tp_report 09:27:09 INFO - PROCESS | 15895 | __start_cc_report 09:27:09 INFO - PROCESS | 15895 | _x_x_mozilla_cycle_collect,1102 09:27:09 INFO - PROCESS | 15895 | __end_cc_report 09:27:09 INFO - PROCESS | 15895 | __startTimestamp1482082029022__endTimestamp 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | ------- Summary: start ------- 09:27:09 INFO - PROCESS | 15895 | Number of tests: 6 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#0] dojo.html Cycles:165 Average:5137.45 Median:5297.95 stddev:559.33 (10.6%) stddev-sans-first:535.48 09:27:09 INFO - PROCESS | 15895 | Values: 3006.6 3046.0 3050.6 3077.5 3086.2 5070.3 5107.2 5130.2 5150.3 5169.3 5142.8 5201.6 5216.4 5219.3 5270.3 4708.9 4719.3 4754.8 4762.8 4793.0 4773.7 4843.3 4845.3 4852.8 4892.3 4643.6 4779.0 4784.8 4786.3 4889.2 4664.0 4664.9 4713.5 4728.7 4731.5 4665.7 4684.1 4684.8 4738.3 4743.2 5178.9 5186.4 5194.9 5196.9 5200.0 5415.0 5446.5 5458.7 5538.3 5548.6 5537.3 5541.1 5586.4 5614.6 5618.4 5207.8 5209.7 5214.6 5219.5 5266.6 5483.3 5512.4 5591.3 5658.8 5666.5 5446.3 5460.5 5462.3 5516.7 5517.8 5490.4 5535.1 5537.2 5541.6 5545.6 5352.7 5395.2 5528.5 5529.8 5533.1 5413.1 5429.4 5444.3 5455.7 5460.3 4208.7 4215.6 4216.8 4217.6 4261.0 3789.7 3793.7 3805.9 3828.2 3848.3 5223.6 5256.4 5260.7 5274.1 5297.9 5529.7 5537.6 5541.2 5541.4 5541.5 5130.2 5252.8 5266.3 5271.0 5316.2 4706.6 4732.8 4787.4 4787.5 4788.1 5672.8 5675.2 5683.3 5683.7 5687.3 5489.9 5508.7 5511.1 5511.6 5514.5 5652.4 5682.7 5695.4 5698.6 5704.1 5442.0 5452.9 5453.2 5455.3 5458.1 5544.1 5544.5 5545.9 5546.9 5548.1 5297.3 5301.7 5313.1 5358.4 5362.2 5348.9 5350.3 5352.6 5370.8 5398.3 5255.5 5259.4 5266.3 5285.3 5314.3 5395.1 5395.9 5399.3 5438.6 5439.1 5285.1 5290.0 5293.1 5296.5 5339.8 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#1] ext.html Cycles:180 Average:63099.03 Median:35768.82 stddev:137059.25 (383.2%) stddev-sans-first:137363.23 09:27:09 INFO - PROCESS | 15895 | Values: 532.9 549.6 565.6 589.7 590.9 31383.4 34266.3 34320.8 39462.4 41126.0 42400.4 42878.0 42904.4 42929.0 42966.6 8553.5 8739.2 8742.9 8744.1 8744.2 7617.1 7773.9 8540.3 8653.7 9979.4 9935.8 10302.2 10302.4 10302.5 10303.6 10234.7 10277.4 10283.9 10287.5 10292.3 9972.1 9978.8 9998.2 10070.7 10083.1 40506.8 40509.2 40534.8 40596.0 40652.6 3130.2 3141.1 3184.5 3220.0 3466.8 30439.4 30833.7 31507.4 31571.1 31635.2 3660.7 3744.2 3775.7 3780.2 3818.2 321675.0 322258.0 323236.0 323718.0 324597.0 54177.6 54315.0 54431.7 54462.5 54632.5 57585.7 57802.7 57817.4 57837.7 57857.0 15596.5 15783.7 15821.5 15829.1 15843.7 11546.6 11580.9 11582.1 11584.8 11621.8 13526.6 13826.7 15377.2 19069.3 19569.7 6433.4 6512.4 6520.6 6662.7 6906.8 888.8 965.2 998.5 1024.5 1029.7 31076.8 31126.7 31172.5 31385.7 31940.8 1008.5 1040.6 1078.4 1091.6 1134.5 15807.2 16246.8 16286.0 16330.9 16462.8 523064.0 523191.0 523277.0 524176.1 525257.0 48977.5 49796.5 49944.5 54040.5 56802.0 1293.8 1329.8 1334.0 1334.9 1336.4 28858.0 30161.8 30255.7 30765.8 30870.9 48983.3 49299.8 49427.3 49463.5 49504.3 433085.0 666728.0 679461.0 684806.0 690723.0 36807.4 36812.0 36839.3 37076.4 37363.8 41050.0 41114.8 41135.8 41250.6 42097.6 35341.3 35600.0 35804.8 35955.8 36238.5 35320.0 35510.8 35632.3 35732.8 35982.8 34849.0 35507.0 35518.8 35578.8 36327.8 30100.7 33031.3 33032.3 33048.5 33110.2 24774.3 24934.3 25032.4 25565.5 25614.7 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#2] jquery.html Cycles:180 Average:27704.26 Median:20375.49 stddev:68762.56 (337.5%) stddev-sans-first:68924.80 09:27:09 INFO - PROCESS | 15895 | Values: 356.7 370.7 379.5 383.7 402.6 14087.3 14558.6 18628.4 18658.2 18672.6 19437.7 20105.5 20234.9 20334.0 20395.1 4527.8 5870.0 6022.6 6092.9 6095.7 6445.6 7089.0 7119.5 7230.6 7236.0 7061.4 7094.2 7105.5 7158.0 7193.7 6945.0 7043.7 7137.5 7179.2 7180.7 6843.9 6884.6 6913.1 6917.9 6918.2 24500.6 24920.0 25079.9 25237.7 25526.5 2351.3 2382.7 2576.7 2717.2 2820.7 14798.8 14889.7 15481.5 15768.9 15812.4 2011.3 2062.9 2066.3 2070.2 2116.6 88075.0 88835.6 90535.0 91095.6 92017.0 16890.3 16982.8 17090.6 17359.7 17382.0 29637.9 29907.6 30038.8 30150.2 30259.7 8986.6 9296.6 9384.3 9534.9 9596.0 7401.6 7550.1 7853.1 8058.0 8078.6 8328.6 8574.4 8666.4 8680.8 8820.9 3082.8 3342.2 3365.5 3397.2 3461.4 914.3 926.3 932.7 938.8 1040.6 17610.9 17992.6 18533.3 18819.8 18888.2 925.1 958.6 973.6 993.4 995.1 9815.7 10312.4 10435.3 10460.2 10482.3 405467.0 410131.4 419502.0 419982.0 421123.0 82620.6 83100.2 84608.0 84948.0 85637.0 948.6 954.3 979.6 981.2 982.0 15728.7 15956.3 16037.6 16598.2 16958.7 35229.0 38854.4 40348.4 40949.8 41807.0 51867.7 53578.5 53673.7 55065.7 56281.7 7941.6 8019.3 8321.6 8327.5 8430.4 11728.4 12689.6 12766.0 12813.1 12868.2 11030.6 11245.5 11501.7 11535.3 11546.7 10186.2 10671.4 10927.4 11425.5 11633.5 11539.8 11725.6 11761.2 11774.7 11886.5 7973.2 8193.7 8318.3 8327.5 8432.6 8249.3 11414.5 11448.7 11508.2 11533.5 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#3] mootools.html Cycles:180 Average:5133.72 Median:5093.00 stddev:2859.49 (56.1%) stddev-sans-first:2852.98 09:27:09 INFO - PROCESS | 15895 | Values: 1297.0 1388.9 1407.5 1520.4 1701.8 6802.4 7531.8 7850.8 7850.8 8019.3 7817.4 7890.9 7899.7 7903.6 7906.0 1872.2 2110.9 2143.8 2144.4 2145.8 1864.6 1872.8 1875.7 2120.1 2198.2 2051.3 2061.4 2065.3 2067.5 2071.6 2104.7 2110.2 2114.7 2119.9 2149.2 2107.6 2111.5 2130.4 2132.5 2134.2 7560.3 7738.0 7742.2 7749.2 7749.3 4736.2 4844.4 4882.6 4884.8 4899.3 2966.5 3010.2 3073.8 3090.9 3092.6 951.8 970.1 978.3 985.7 1013.2 10627.7 10749.7 10852.7 10882.8 10911.0 4145.3 4285.6 4326.2 4415.2 4422.0 9044.5 9050.9 9126.1 9131.1 9138.2 4867.4 4902.5 4907.2 4911.7 4914.3 4398.3 4491.2 4558.3 4566.2 4571.6 3389.3 3458.0 3579.1 3601.3 3756.2 1168.5 1175.1 1182.0 1191.5 1201.2 727.1 744.8 745.7 868.5 920.1 5029.1 5225.9 5254.7 5359.2 5425.6 911.3 924.3 925.6 928.8 946.3 3493.0 3623.5 3642.5 3655.0 3768.1 9223.4 9582.6 9665.8 9694.4 9721.6 9256.6 9311.9 9427.7 9440.7 9442.1 7045.0 7123.1 7218.1 7279.9 7292.7 4834.8 4874.8 4886.0 5010.2 5156.8 8625.3 8630.5 8656.5 8717.3 8751.9 8637.8 8910.4 8913.3 9004.6 9054.5 6983.7 7037.7 7091.3 7092.1 7093.8 6769.7 7008.7 7017.2 7312.3 7339.1 6917.2 6924.1 7017.2 7041.1 7120.3 6634.3 6726.3 6760.0 6762.7 6817.6 6100.5 6211.6 6220.7 6223.5 6228.5 4538.3 4550.7 4571.5 4617.5 4661.4 4413.8 4421.7 4511.6 4621.6 4712.7 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#4] prototype.html Cycles:180 Average:4534.51 Median:5171.00 stddev:1323.06 (25.6%) stddev-sans-first:1289.16 09:27:09 INFO - PROCESS | 15895 | Values: 361.7 364.9 367.1 372.8 377.0 5115.4 5205.4 5210.0 5246.8 5269.8 4963.5 4972.2 5104.5 5124.5 5212.0 4001.8 4115.4 4129.4 4136.5 4150.2 4050.3 4058.0 4077.3 4086.3 4111.9 3901.2 3957.7 4061.8 4073.4 4087.0 4054.8 4062.2 4080.4 4083.9 4093.5 3681.6 3685.1 3687.7 3689.0 3691.6 4830.3 5021.8 5085.7 5093.7 5107.6 4429.6 4475.0 4488.3 4491.0 4498.9 5321.5 5323.8 5365.2 5420.2 5429.6 3726.4 3755.4 3807.2 3809.4 3815.6 5497.9 5510.9 5531.4 5537.5 5550.5 3923.5 3925.8 3929.6 3941.5 3944.8 3940.7 3945.8 3968.6 4003.3 4027.0 4491.5 4542.4 4542.7 4562.5 4583.9 5353.3 5359.4 5373.5 5373.7 5374.1 1332.5 1338.6 1344.6 1349.6 1352.7 874.0 879.8 922.4 933.2 934.7 5034.9 5035.1 5062.5 5072.8 5076.8 5269.9 5333.8 5351.5 5364.7 5365.5 4824.6 4965.3 4999.6 5051.8 5068.3 2790.1 2809.6 2821.3 2842.8 2848.2 5436.5 5439.6 5457.5 5484.9 5489.5 5313.7 5632.2 5641.0 5641.2 5658.2 5712.1 5733.1 5735.6 5742.8 5746.4 5302.9 5484.4 5584.5 5586.3 5591.7 5496.3 5512.2 5512.4 5517.9 5535.6 5728.6 5733.2 5764.3 5770.0 5775.5 3988.4 4119.7 4171.4 4177.3 4201.2 5538.0 5548.4 5549.4 5550.6 5561.6 5414.8 5425.5 5432.8 5442.5 5447.9 5209.6 5411.6 5429.5 5446.7 5456.6 5136.6 5264.7 5339.9 5364.5 5368.4 5314.9 5469.8 5474.8 5481.8 5491.9 5331.5 5360.3 5387.4 5389.5 5393.8 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - PROCESS | 15895 | [#5] yui.html Cycles:180 Average:17294.91 Median:4628.68 stddev:22607.53 (488.4%) stddev-sans-first:22636.40 09:27:09 INFO - PROCESS | 15895 | Values: 649.5 697.9 708.8 752.5 758.4 14399.1 15195.8 15910.8 16193.3 16202.1 14038.4 14320.5 14384.3 14444.8 14484.1 776.7 795.1 795.8 796.1 798.1 666.5 690.1 764.9 791.7 795.9 829.5 838.7 839.7 840.6 842.0 831.4 831.6 832.5 832.8 833.0 515.0 531.1 532.6 608.8 609.4 12804.2 14780.5 15204.2 15320.4 15786.4 12890.0 12941.5 12995.6 13100.0 13443.3 11449.5 11501.4 11616.7 11686.8 12175.8 3439.9 3460.7 3464.2 3476.9 3586.1 76622.7 77420.2 77816.0 79064.2 80537.4 10134.8 10227.8 10501.7 10509.4 10616.3 19117.1 19779.9 19784.2 20483.7 20549.0 7424.3 7514.4 7643.2 7744.5 7854.7 4325.7 4393.1 4397.9 4476.7 4523.1 3447.1 3502.5 3519.2 3541.9 3550.5 590.8 596.3 614.8 616.0 617.8 533.3 553.3 632.6 642.9 654.7 8320.5 9448.9 9621.6 9654.2 9746.5 670.1 678.0 692.7 701.6 709.3 4328.2 4572.9 4624.2 4633.1 4777.9 79102.2 80581.2 80881.0 81890.2 83641.0 57716.0 68791.3 70669.3 71649.6 72368.6 60522.7 62369.7 63804.7 66818.7 67105.7 8917.1 8969.2 9028.5 9235.6 9249.2 45505.8 45950.0 46441.8 46563.0 47122.8 45386.5 49150.3 51328.2 52699.0 53152.5 11905.5 11922.1 12040.0 12262.3 13110.1 14288.4 14557.0 14620.8 14632.3 14705.7 12704.7 12899.9 13042.9 13091.3 13342.2 11412.7 11633.8 11659.2 12151.3 12211.5 11795.2 12272.6 12421.7 12583.4 12720.0 10701.0 11029.7 11183.3 11208.1 11373.9 10267.8 10718.8 10769.2 10901.7 10993.7 09:27:09 INFO - PROCESS | 15895 | -------- Summary: end -------- 09:27:09 INFO - PROCESS | 15895 | 09:27:09 INFO - TEST-INFO | 15895: exit 0 09:27:09 INFO - TEST-OK | dromaeo_css | took 1118490ms 09:27:09 INFO - TEST-START | kraken 09:27:09 INFO - operating with platform_type : linux_ 09:27:09 INFO - Initialising browser for kraken test... 09:27:09 INFO - TEST-INFO | started process 16199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptAcYGR/profile http://localhost:52679/getInfo.html) 09:27:14 INFO - PROCESS | 16199 | __metrics Screen width/height:1600/1200 09:27:14 INFO - PROCESS | 16199 | colorDepth:24 09:27:14 INFO - PROCESS | 16199 | Browser inner width/height: 1024/697 09:27:14 INFO - PROCESS | 16199 | __metrics 09:27:15 INFO - PROCESS | 16199 | 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" 09:27:15 INFO - PROCESS | 16199 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:16 INFO - PROCESS | 16199 | console.error: PushService: 09:27:16 INFO - PROCESS | 16199 | stateChangeProcessEnqueue: Error transitioning state 09:27:16 INFO - PROCESS | 16199 | UnknownError 09:27:16 INFO - TEST-INFO | 16199: exit 0 09:27:16 INFO - Browser initialized. 09:27:16 INFO - Running cycle 1/1 for kraken test... 09:27:16 INFO - TEST-INFO | started process 16284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptAcYGR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:28 INFO - PROCESS | 16284 | RSS: Main: 154411008 09:27:28 INFO - PROCESS | 16284 | 09:27:31 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52679/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:31 INFO - PROCESS | 16284 | RSS: Main: 208789504 09:27:31 INFO - PROCESS | 16284 | 09:27:34 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52679/tests/kraken/driver.html?testName=audio-dft) 09:27:35 INFO - PROCESS | 16284 | RSS: Main: 186331136 09:27:35 INFO - PROCESS | 16284 | 09:27:38 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52679/tests/kraken/driver.html?testName=audio-fft) 09:27:38 INFO - PROCESS | 16284 | RSS: Main: 268345344 09:27:38 INFO - PROCESS | 16284 | 09:27:40 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52679/tests/kraken/driver.html?testName=audio-oscillator) 09:27:41 INFO - PROCESS | 16284 | RSS: Main: 179908608 09:27:41 INFO - PROCESS | 16284 | 09:27:43 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52679/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:43 INFO - PROCESS | 16284 | RSS: Main: 183898112 09:27:43 INFO - PROCESS | 16284 | 09:27:47 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52679/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:47 INFO - PROCESS | 16284 | RSS: Main: 222748672 09:27:47 INFO - PROCESS | 16284 | 09:27:51 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52679/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:51 INFO - PROCESS | 16284 | RSS: Main: 222572544 09:27:51 INFO - PROCESS | 16284 | 09:27:54 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52679/tests/kraken/driver.html?testName=json-parse-financial) 09:27:55 INFO - PROCESS | 16284 | RSS: Main: 221024256 09:27:55 INFO - PROCESS | 16284 | 09:27:56 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52679/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:57 INFO - PROCESS | 16284 | RSS: Main: 183136256 09:27:57 INFO - PROCESS | 16284 | 09:27:58 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:59 INFO - PROCESS | 16284 | RSS: Main: 191385600 09:27:59 INFO - PROCESS | 16284 | 09:28:01 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:02 INFO - PROCESS | 16284 | RSS: Main: 199720960 09:28:02 INFO - PROCESS | 16284 | 09:28:04 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:05 INFO - PROCESS | 16284 | RSS: Main: 208211968 09:28:05 INFO - PROCESS | 16284 | 09:28:08 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:08 INFO - PROCESS | 16284 | RSS: Main: 204791808 09:28:08 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | Cycle 1(1): loaded http://localhost:52679/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52679/tests/kraken/driver.html?testName=ai-astar) 09:28:10 INFO - PROCESS | 16284 | RSS: Main: 256253952 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | __start_tp_report 09:28:10 INFO - PROCESS | 16284 | _x_x_mozilla_page_load 09:28:10 INFO - PROCESS | 16284 | _x_x_mozilla_page_load_details 09:28:10 INFO - PROCESS | 16284 | |i|pagename|runs| 09:28:10 INFO - PROCESS | 16284 | |0;ai-astar;110;112;111;112;113;112;112;114;111;111 09:28:10 INFO - PROCESS | 16284 | |1;audio-beat-detection;154;118;120;156;121;126;120;118;141;120 09:28:10 INFO - PROCESS | 16284 | |2;audio-dft;160;160;160;161;161;160;160;161;161;160 09:28:10 INFO - PROCESS | 16284 | |3;audio-fft;75;75;76;110;76;75;75;75;92;75 09:28:10 INFO - PROCESS | 16284 | |4;audio-oscillator;83;83;111;83;83;83;104;83;83;106 09:28:10 INFO - PROCESS | 16284 | |5;imaging-gaussian-blur;127;127;127;127;128;127;127;127;127;126 09:28:10 INFO - PROCESS | 16284 | |6;imaging-darkroom;147;146;145;146;146;146;145;146;146;145 09:28:10 INFO - PROCESS | 16284 | |7;imaging-desaturate;95;95;95;95;96;96;96;96;95;96 09:28:10 INFO - PROCESS | 16284 | |8;json-parse-financial;54;51;52;52;52;52;52;53;53;52 09:28:10 INFO - PROCESS | 16284 | |9;json-stringify-tinderbox;64;63;63;64;64;64;64;64;63;63 09:28:10 INFO - PROCESS | 16284 | |10;stanford-crypto-aes;81;81;90;84;81;93;80;80;83;80 09:28:10 INFO - PROCESS | 16284 | |11;stanford-crypto-ccm;130;132;140;144;130;128;126;125;163;142 09:28:10 INFO - PROCESS | 16284 | |12;stanford-crypto-pbkdf2;168;167;168;165;165;169;163;172;164;164 09:28:10 INFO - PROCESS | 16284 | |13;stanford-crypto-sha256-iterative;68;63;63;63;62;62;64;63;61;64 09:28:10 INFO - PROCESS | 16284 | __end_tp_report 09:28:10 INFO - PROCESS | 16284 | __start_cc_report 09:28:10 INFO - PROCESS | 16284 | _x_x_mozilla_cycle_collect,1668 09:28:10 INFO - PROCESS | 16284 | __end_cc_report 09:28:10 INFO - PROCESS | 16284 | __startTimestamp1482082090910__endTimestamp 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | ------- Summary: start ------- 09:28:10 INFO - PROCESS | 16284 | Number of tests: 14 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:1.14 (1.0%) stddev-sans-first:1.00 09:28:10 INFO - PROCESS | 16284 | Values: 110.0 112.0 111.0 112.0 113.0 112.0 112.0 114.0 111.0 111.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:123.50 stddev:15.11 (12.2%) stddev-sans-first:13.14 09:28:10 INFO - PROCESS | 16284 | Values: 154.0 118.0 120.0 156.0 121.0 126.0 120.0 118.0 141.0 120.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 09:28:10 INFO - PROCESS | 16284 | Values: 160.0 160.0 160.0 161.0 161.0 160.0 160.0 161.0 161.0 160.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#3] audio-fft Cycles:10 Average:80.40 Median:75.00 stddev:11.66 (15.6%) stddev-sans-first:12.21 09:28:10 INFO - PROCESS | 16284 | Values: 75.0 75.0 76.0 110.0 76.0 75.0 75.0 75.0 92.0 75.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:83.00 stddev:11.72 (14.1%) stddev-sans-first:12.13 09:28:10 INFO - PROCESS | 16284 | Values: 83.0 83.0 111.0 83.0 83.0 83.0 104.0 83.0 83.0 106.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#5] imaging-gaussian-blur Cycles:10 Average:127.00 Median:127.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 09:28:10 INFO - PROCESS | 16284 | Values: 127.0 127.0 127.0 127.0 128.0 127.0 127.0 127.0 127.0 126.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 09:28:10 INFO - PROCESS | 16284 | Values: 147.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:28:10 INFO - PROCESS | 16284 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.60 09:28:10 INFO - PROCESS | 16284 | Values: 54.0 51.0 52.0 52.0 52.0 52.0 52.0 53.0 53.0 52.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:64.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:28:10 INFO - PROCESS | 16284 | Values: 64.0 63.0 63.0 64.0 64.0 64.0 64.0 64.0 63.0 63.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:82.00 stddev:4.57 (5.6%) stddev-sans-first:4.77 09:28:10 INFO - PROCESS | 16284 | Values: 81.0 81.0 90.0 84.0 81.0 93.0 80.0 80.0 83.0 80.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:136.00 stddev:11.63 (8.6%) stddev-sans-first:12.13 09:28:10 INFO - PROCESS | 16284 | Values: 130.0 132.0 140.0 144.0 130.0 128.0 126.0 125.0 163.0 142.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.50 stddev:2.80 (1.7%) stddev-sans-first:2.92 09:28:10 INFO - PROCESS | 16284 | Values: 168.0 167.0 168.0 165.0 165.0 169.0 163.0 172.0 164.0 164.0 09:28:10 INFO - PROCESS | 16284 | 09:28:10 INFO - PROCESS | 16284 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.89 (3.0%) stddev-sans-first:0.97 09:28:10 INFO - PROCESS | 16284 | Values: 68.0 63.0 63.0 63.0 62.0 62.0 64.0 63.0 61.0 64.0 09:28:10 INFO - PROCESS | 16284 | -------- Summary: end -------- 09:28:10 INFO - PROCESS | 16284 | 09:28:11 INFO - TEST-INFO | 16284: exit 0 09:28:11 INFO - TEST-OK | kraken | took 62064ms 09:28:11 INFO - SUITE-END | took 1180s 09:28:12 INFO - Completed test suite (00:19:40) 09:28:12 INFO - javascript benchmark 09:28:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3006.639203295604, 3045.9847700761507, 3050.6491753448345, 3077.522983937488, 3086.2130156810026, 5070.29015937769, 5107.208382700683, 5130.230465430178, 5150.330457040584, 5169.3021442105255, 5142.794288228476, 5201.635885488017, 5216.400113986892, 5219.347581552306, 5270.341207349082, 4708.90582188356, 4719.315699223611, 4754.78603462845, 4762.78567464465, 4792.976035119819, 4773.6897101688455, 4843.346148269974, 4845.321654968289, 4852.781624826891, 4892.290617860408, 4643.558861908147, 4778.95221047792, 4784.784684689197, 4786.2581299898575, 4889.168841296971, 4664.020555683311, 4664.883377915546, 4713.457952335491, 4728.66899317048, 4731.455882573479, 4665.743384113873, 4684.086603112394, 4684.765761711935, 4738.3129446230105, 4743.217369134089, 5178.870528236786, 5186.40356359016, 5194.896102077975, 5196.896062078775, 5200.037992971274, 5415.0, 5446.537044351235, 5458.727063646802, 5538.280023596947, 5548.611597188198, 5537.252470916415, 5541.113421852484, 5586.441355864422, 5614.606977511576, 5618.438156184389, 5207.791688332462, 5209.713465759344, 5214.556762675177, 5219.504147105981, 5266.578673706094, 5483.287172667608, 5512.4211957744665, 5591.30108736408, 5658.773649053992, 5666.546676265889, 5446.319210098738, 5460.453954604508, 5462.3445186577865, 5516.696581687966, 5517.779288828402, 5490.423505531941, 5535.142052981794, 5537.169424586345, 5541.612087153861, 5545.584081193927, 5352.678839269578, 5395.21769343443, 5528.474794894479, 5529.834104976857, 5533.059379905426, 5413.052715774724, 5429.375621803543, 5444.3194600674915, 5455.727213639302, 5460.290162278879, 4208.705390622688, 4215.557366476476, 4216.831326746896, 4217.641500472494, 4261.0, 3789.7347185696735, 3793.7154713396053, 3805.8858234253016, 3828.196078823423, 3848.2688289224957, 5223.5559977402345, 5256.448072952285, 5260.710660913686, 5274.12976858814, 5297.947020529746, 5529.695866727368, 5537.58468114893, 5541.168824676331, 5541.418151094077, 5541.501264886179, 5130.153524668388, 5252.842414727565, 5266.315379000782, 5270.973645131749, 5316.175992721134, 4706.647001474903, 4732.810687572527, 4787.3537072495465, 4787.497312782178, 4788.066327066258, 5672.7730890763905, 5675.2054712340305, 5683.26117604717, 5683.687397193194, 5687.289088863975, 5489.945100548944, 5508.697021663847, 5511.145772405283, 5511.559075274049, 5514.531264842373, 5652.434756524314, 5682.659040457585, 5695.430456954271, 5698.572607054404, 5704.1443783432815, 5442.0, 5452.91820622683, 5453.182022696627, 5455.3453585568395, 5458.1266997280245, 5544.112941929272, 5544.528715059265, 5545.861353466195, 5546.889062218814, 5548.056830338772, 5297.311349524537, 5301.734913254399, 5313.070212712682, 5358.437364076872, 5362.222477740784, 5348.866278343073, 5350.304460420276, 5352.625316227826, 5370.785168593213, 5398.271233383443, 5255.474452554714, 5259.368875734936, 5266.34170728659, 5285.260063591023, 5314.335708036496, 5395.136778115482, 5395.919061214006, 5399.2980912481125, 5438.646487978269, 5439.129739241703, 5285.127953887565, 5289.973550132225, 5293.073712100444, 5296.470352964827, 5339.839804805862], "value": 5101.16677545508, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.9014132385507, 549.6125231711641, 565.5786439102869, 589.6933594530844, 590.8552404660859, 31383.372332553365, 34266.31467370654, 34320.828395857985, 39462.408063879, 41126.0, 42400.36399454011, 42878.0, 42904.356434653506, 42928.99999999992, 42966.570334296644, 8553.529555874424, 8739.213470787628, 8742.86885696715, 8744.081871403507, 8744.213020828125, 7617.124030736453, 7773.883391749128, 8540.274076703488, 8653.740387788344, 9979.401235925867, 9935.751606209866, 10302.227332950059, 10302.433366164858, 10302.48487575622, 10303.63937262196, 10234.744131396737, 10277.434741089237, 10283.948580257125, 10287.537060832243, 10292.279540432173, 9972.102510773993, 9978.800423991552, 9998.150157236643, 10070.748231294203, 10083.092521673085, 40506.79746601277, 40509.189816203805, 40534.797326013184, 40596.0, 40652.59347406518, 3130.20179854138, 3141.1204862638497, 3184.471453702236, 3219.983900080508, 3466.757326987112, 30439.391212175855, 30833.69166308353, 31507.36985260305, 31571.10796775109, 31635.20911977219, 3660.7437479376454, 3744.1762812181278, 3775.6601905828607, 3780.206156707095, 3818.236352729465, 321675.0, 322258.0, 323236.0, 323718.0, 324597.0, 54177.56200970887, 54315.0, 54431.72784136133, 54462.45537544653, 54632.453675463534, 57585.712071439375, 57802.7109864448, 57817.42182578205, 57837.710811446515, 57857.0, 15596.532104036896, 15783.68432631364, 15821.525354239391, 15829.050256984618, 15843.683126337408, 11546.59586914454, 11580.88419115815, 11582.131340149523, 11584.826227606593, 11621.76756464883, 13526.59420217395, 13826.65433364174, 15377.154256515776, 19069.332573359865, 19569.706454403196, 6433.356664333529, 6512.381323774234, 6520.641364724891, 6662.70077334915, 6906.827329316707, 888.7778055486128, 965.2471072563412, 998.4857798233916, 1024.5235965276183, 1029.6550655530332, 31076.844615776776, 31126.68873311283, 31172.53241201384, 31385.686143138733, 31940.84029579837, 1008.4705529596873, 1040.6409788622914, 1078.4338222433128, 1091.645215305029, 1134.5405110930087, 15807.215018693929, 16246.75629865553, 16286.0, 16330.856840021084, 16462.75305870413, 523064.0, 523191.0, 523277.0, 524176.1318189148, 525257.0, 48977.51022489801, 49796.50203497991, 49944.50055499398, 54040.45959540433, 56802.0, 1293.780057390227, 1329.7805862032656, 1333.973320533584, 1334.9332533373292, 1336.4119787293755, 28858.0, 30161.849190753906, 30255.69744302617, 30765.846170769004, 30870.923366491243, 48983.26525102055, 49299.82351853004, 49427.258591120444, 49463.50536494589, 49504.2574361392, 433085.0, 666728.0, 679461.0, 684806.0, 690723.0, 36807.44788828116, 36812.0, 36839.263214735016, 37076.44385334168, 37363.813180933925, 41050.0, 41114.79442602768, 41135.7943210282, 41250.58749412587, 42097.579024210594, 35341.29317413689, 35600.0, 35804.82097589495, 35955.82022089873, 36238.45642315315, 35320.0, 35510.82244588864, 35632.28735425329, 35732.82133589315, 35982.82008590045, 34849.0, 35507.0, 35518.822405887804, 35578.8221058893, 36327.81836090803, 30100.698993009788, 33031.33937321192, 33032.33935321328, 33048.50427243641, 33110.17224569405, 24774.25677229686, 24934.251972440856, 25032.374190644652, 25565.488690226462, 25614.743852561234], "value": 19365.64435573408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.7039357333413, 370.66006386911323, 379.4554813842136, 383.67196047379497, 402.589358853969, 14087.295635218237, 14558.56324310271, 18628.441146765585, 18658.160382782775, 18672.626547469077, 19437.70843437346, 20105.497362565944, 20234.89882550585, 20334.0, 20395.08222130004, 4527.841525546606, 5869.9706501467435, 6022.608530445529, 6092.878142437148, 6095.664738439384, 6445.645489498076, 7089.0, 7119.50163488556, 7230.638468076601, 7235.963820180892, 7061.399781018619, 7094.219635840053, 7105.538140020908, 7158.033665455175, 7193.676284567181, 6944.992976018449, 7043.718251269943, 7137.464690148234, 7179.210286868441, 7180.748673796418, 6843.8973415398805, 6884.552504087218, 6913.1358580177475, 6917.9308206918295, 6918.169819621678, 24500.632490512657, 24920.0, 25079.874600627063, 25237.747622523722, 25526.489470210676, 2351.341624345186, 2382.702162229721, 2576.6779152605923, 2717.184844546628, 2820.732030457103, 14798.778018329735, 14889.70220595582, 15481.458148964732, 15768.92115539415, 15812.36750529989, 2011.265887950902, 2062.876227426344, 2066.2664754012258, 2070.1615845582564, 2116.6295898217904, 88075.0, 88835.55582222047, 90535.0, 91095.63356549724, 92017.0, 16890.324387024382, 16982.830171698126, 17090.572735681708, 17359.739603905953, 17382.0, 29637.85181074081, 29907.551386729217, 30038.849805750833, 30150.246243844078, 30259.69740302613, 8986.595603197871, 9296.628134874665, 9384.296177786557, 9534.85030889418, 9596.0, 7401.629918504053, 7550.131734850453, 7853.0943550885395, 8057.959710201529, 8078.636461359135, 8328.583570821434, 8574.399792014623, 8666.393352465393, 8680.826383472422, 8820.898282298762, 3082.799618024821, 3342.2479942013338, 3365.495175723612, 3397.2016576104597, 3461.3769521486374, 914.3234006834895, 926.3006430145198, 932.6595792535676, 938.8028514012134, 1040.5941682743735, 17610.911945440446, 17992.640147196984, 18533.258669653063, 18819.786258933065, 18888.150033248538, 925.1488630459993, 958.5734348214977, 973.571628483465, 993.3592832622918, 995.0994350113159, 9815.656452024145, 10312.43281619503, 10435.32170408921, 10460.215483838743, 10482.266241363184, 405467.0, 410131.392116474, 419502.0, 419982.0, 421123.0, 82620.58689706514, 83100.16899831046, 84608.0, 84948.0, 85637.0, 948.62055177928, 954.2795189631784, 979.5836769372988, 981.215027977615, 981.9901800982042, 15728.685426291639, 15956.281967311732, 16037.599060023593, 16598.17009149562, 16958.660826783642, 35229.0, 38854.41718374283, 40348.394774077824, 40949.79525102475, 41807.0, 51867.740661296455, 53578.464215357344, 53673.731631341594, 55065.72467137639, 56281.71859140678, 7941.563214023284, 8019.318357939641, 8321.583920804056, 8327.541985190872, 8430.367722420966, 11728.41357932117, 12689.619311420673, 12766.0, 12813.103082784115, 12868.22790632565, 11030.613928512463, 11245.493952772309, 11501.712457188638, 11535.250208736405, 11546.653600392001, 10186.236032297755, 10671.35971841692, 10927.398993055458, 11425.54403782542, 11633.47649355764, 11539.769204615694, 11725.589604363806, 11761.176717630025, 11774.705632359259, 11886.465109070286, 7973.242541958504, 8193.67225310981, 8318.334533237448, 8327.50034997902, 8432.620532075949, 8249.257566819015, 11414.48634811419, 11448.656540303804, 11508.194426390073, 11533.538658453568], "value": 9997.76652025054, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.007789041383, 1388.9027768056235, 1407.4933024111322, 1520.399442220323, 1701.7702610147635, 6802.353776391241, 7531.811704707385, 7850.76447706569, 7850.803729906741, 8019.318357939582, 7817.374610031198, 7890.92109078909, 7899.723509677148, 7903.6048197590035, 7905.960470197641, 1872.1575291118984, 2110.8627939183943, 2143.7641859395453, 2144.421006328298, 2145.839062070343, 1864.6363959027994, 1872.7659042619673, 1875.6998880179174, 2120.0777661717125, 2198.1866709317574, 2051.3333166720877, 2061.40219336393, 2065.3494149342982, 2067.514134178467, 2071.6167509010806, 2104.67377556479, 2110.155937624947, 2114.6616541353387, 2119.925802596917, 2149.215536329244, 2107.6417009108336, 2111.514351699118, 2130.3502431758307, 2132.4988627672483, 2134.2316765964397, 7560.319571238615, 7738.0, 7742.187070357644, 7749.225077492262, 7749.341305988998, 4736.242201247818, 4844.418669759616, 4882.560569548722, 4884.755762211911, 4899.314096026524, 2966.480865848468, 3010.247438140465, 3073.754099672043, 3090.9381812363627, 3092.6288845338704, 951.8001219743793, 970.1317320997738, 978.2614126334572, 985.6747273399761, 1013.1945103642548, 10627.681169564925, 10749.731256718567, 10852.728681782939, 10882.782344353147, 10911.0, 4145.316022856256, 4285.635720963753, 4326.19965306413, 4415.205263052617, 4422.0, 9044.547772611342, 9050.909490905007, 9126.13301736334, 9131.086891310948, 9138.223251023737, 4867.415910090812, 4902.50974902514, 4907.1903135982875, 4911.729854857946, 4914.262860570871, 4398.274284743045, 4491.214037543416, 4558.270676691714, 4566.20091483989, 4571.588557029883, 3389.339078879644, 3458.049036514928, 3579.1231148368297, 3601.3157500074904, 3756.1924186300075, 1168.5384273212035, 1175.1480176871871, 1181.9881801181878, 1191.4876603060593, 1201.1531870031606, 727.0802434919831, 744.7505085796319, 745.6868115391549, 868.4919322196586, 920.0569416348296, 5029.1450453423, 5225.947740522623, 5254.65844720092, 5359.249705041298, 5425.601526481177, 911.3483859040817, 924.3437159616612, 925.5881132895797, 928.767808047988, 946.2619157057507, 3492.9825350873084, 3623.5289412376223, 3642.453631955228, 3654.9634503654624, 3768.1333293342136, 9223.400478968846, 9582.616695332108, 9665.806683866283, 9694.36986595869, 9721.562529686182, 9256.583453744597, 9311.90688093124, 9427.717168484956, 9440.669576564787, 9442.055794420641, 7044.999999999897, 7123.145222573221, 7218.133823941055, 7279.854402911912, 7292.744753933587, 4834.758262086952, 4874.78063487151, 4886.0, 5010.223415370623, 5156.845294641167, 8625.266852317623, 8630.482171069756, 8656.48061116335, 8717.346199035224, 8751.862257906432, 8637.82724345497, 8910.420822646507, 8913.320412603929, 9004.59479323419, 9054.54727263621, 6983.685734142077, 7037.7184912602925, 7091.2554181810165, 7092.148942126978, 7093.787186384417, 6769.729210831511, 7008.719651213894, 7017.157941047107, 7312.268773122645, 7339.082614673166, 6917.204521480095, 6924.134483189601, 7017.228104908558, 7041.119860017498, 7120.287971202838, 6634.270230274569, 6726.260111387841, 6760.0, 6762.695678694569, 6817.5909445433435, 6100.511959043355, 6211.565190436807, 6220.720067596879, 6223.533235007483, 6228.532860035425, 4538.2738761797955, 4550.681452298308, 4571.47428045779, 4617.492075871584, 4661.440627124767, 4413.757243351646, 4421.690481666253, 4511.571400716926, 4621.60716339115, 4712.7172369656555], "value": 4137.795599426558, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.6564263949247, 364.937960546707, 367.0566643725625, 372.8024147201982, 376.9830357633906, 5115.437301896793, 5205.401378841434, 5210.0, 5246.816361427351, 5269.841904742859, 4963.454020057799, 4972.179590367585, 5104.515071068252, 5124.538791508763, 5211.973940130303, 4001.8199181036925, 4115.382692596105, 4129.4012368206595, 4136.544980052192, 4150.232207041692, 4050.2911990401562, 4058.0, 4077.3476243800997, 4086.3257562502154, 4111.8972025699295, 3901.239258344624, 3957.7031722620773, 4061.7562946223175, 4073.3523209913646, 4086.979565102185, 4054.776987265699, 4062.2376783787013, 4080.3879418087226, 4083.8774836754797, 4093.4678491796176, 3681.5766186888313, 3685.060309621056, 3687.7049836013052, 3689.0, 3691.630836916314, 4830.3237546743485, 5021.774020169101, 5085.694858308513, 5093.745312734385, 5107.61692873036, 4429.579189976947, 4475.0, 4488.259437192859, 4491.0, 4498.910021799546, 5321.547668448186, 5323.840284791462, 5365.248865158881, 5420.214068960018, 5429.592780541475, 3726.3851464508325, 3755.4414917809077, 3807.162424266657, 3809.4095415210686, 3815.5993620669983, 5497.917531237034, 5510.9448905510835, 5531.3915469298345, 5537.529310008654, 5550.4727050930505, 3923.509561304837, 3925.803709814498, 3929.646331830149, 3941.4876066111224, 3944.8422063117155, 3940.6798722427734, 3945.8421663133727, 3968.6229808168177, 4003.2994226010333, 4026.99988508563, 4491.483479399911, 4542.409486766699, 4542.705328981225, 4562.452505699337, 4583.945692490745, 5353.304070470814, 5359.410464848864, 5373.543248823894, 5373.731313434313, 5374.140137578047, 1332.4670131947298, 1338.618493729302, 1344.610063081715, 1349.601867449105, 1352.6821197018692, 873.9737807865774, 879.7800549862534, 922.3904287019194, 933.1508327422149, 934.6822080492665, 5034.89930201394, 5035.1188542005, 5062.4684408137355, 5072.847814565569, 5076.771545280471, 5269.894602107936, 5333.813316533902, 5351.528329709283, 5364.7317634118135, 5365.54392876602, 4824.638152138534, 4965.32968049316, 4999.600031997432, 5051.848444546669, 5068.290439338495, 2790.074848354629, 2809.578563215534, 2821.3369858083333, 2842.815217010888, 2848.1882663441233, 5436.456354364532, 5439.646422982572, 5457.508824205761, 5484.862878427992, 5489.533389661844, 5313.654612450178, 5632.211490391349, 5641.041023026095, 5641.182028605911, 5658.236138121383, 5712.057510510802, 5733.082706766981, 5735.598508104476, 5742.7702891883855, 5746.3966283541295, 5302.893942121059, 5484.369297530835, 5584.497395234448, 5586.273784408001, 5591.664500130005, 5496.340439147328, 5512.228288039758, 5512.4211957745465, 5517.917231241454, 5535.640183388067, 5728.598998070095, 5733.226014487991, 5764.250647415809, 5769.9711501442225, 5775.5090817281, 3988.4216788566077, 4119.661110139157, 4171.353440216772, 4177.289860723624, 4201.18076974987, 5537.972310138423, 5548.445155484419, 5549.445055494418, 5550.611457197957, 5561.55183786039, 5414.756335964812, 5425.484578964992, 5432.809851655173, 5442.510174084351, 5447.918281225706, 5209.557187638941, 5411.645934018715, 5429.484199001088, 5446.665566935968, 5456.61803673739, 5136.589072874086, 5264.684118952875, 5339.932013597374, 5364.544013758874, 5368.409474957673, 5314.946850531445, 5469.835904922858, 5474.822913073708, 5481.753321100639, 5491.862703432446, 5331.467552912336, 5360.311501876897, 5387.434319396407, 5389.487998640123, 5393.757280922447], "value": 4122.742256618748, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.5031301054693, 697.8743826111302, 708.8015355700403, 752.4893455023682, 758.3667637522668, 14399.136051836884, 15195.772063419045, 15910.84089159111, 16193.271302791372, 16202.108884011375, 14038.368273427695, 14320.498782542612, 14384.280785960713, 14444.783328250085, 14484.13095214288, 776.6932061835586, 795.0935933036343, 795.7692269241913, 796.0765512006074, 798.1061211443192, 666.5001249063203, 690.1132045321763, 764.8776195808672, 791.6674996501455, 795.8965334506535, 829.5022986208257, 838.6771093129158, 839.7228914458215, 840.5881118252044, 841.9873701894477, 831.3806214370272, 831.6049876308796, 832.4797001873828, 832.7834762961613, 832.9750107496785, 515.0368810324679, 531.0573731626356, 532.6271609873104, 608.8203979825962, 609.3723464831187, 12804.231746095264, 14780.482683106147, 15204.16377099259, 15320.387184512718, 15786.368545258176, 12890.0, 12941.48234070636, 12995.610131696065, 13100.0, 13443.327833608379, 11449.542018319256, 11501.367424791633, 11616.709582260426, 11686.824697629543, 12175.82307865116, 3439.94840077399, 3460.6885380315894, 3464.22055037617, 3476.947845782315, 3586.0855481852223, 76622.69967583241, 77420.22579774186, 77816.0, 79064.20935790626, 80537.38925221522, 10134.797304054026, 10227.795444091076, 10501.684949451528, 10509.421981791074, 10616.25686201974, 19117.13972871224, 19779.901100494404, 19784.208631654572, 20483.69274460884, 20549.0, 7424.294692004251, 7514.361279291322, 7643.235676432312, 7744.535327880345, 7854.72508462201, 4325.675574331937, 4393.0994146199955, 4397.912041759147, 4476.664250181249, 4523.140603285366, 3447.1382154461385, 3502.492138639883, 3519.2257703305745, 3541.911452213664, 3550.467429885538, 590.7784580782206, 596.272547492059, 614.827848202504, 616.0236025899001, 617.8486270863577, 533.2827347217985, 553.3415235869383, 632.5666918160982, 642.8746394453037, 654.6955665615417, 8320.459170154041, 9448.88959833978, 9621.629710921994, 9654.179394751385, 9746.463944482979, 670.1321788284183, 678.0270312102149, 692.6986760759086, 701.638656092113, 709.3296834491459, 4328.220920234388, 4572.862814115582, 4624.213883639789, 4633.142868569325, 4777.928331075037, 79102.20897791063, 80581.19418805854, 80881.0, 81890.18109818826, 83641.0, 57716.0, 68791.31208687849, 70669.2933070673, 71649.64175179091, 72368.63815680888, 60522.69738651367, 62369.68815155986, 63804.68097659482, 66818.66590667015, 67105.6644716783, 8917.063708310534, 8969.192772650493, 9028.503432311287, 9235.584398702209, 9249.16757491829, 45505.77247113743, 45950.0, 46441.76779116218, 46563.0, 47122.82192945204, 45386.54613453955, 49150.26274605812, 51328.230076549626, 52699.0, 53152.46847531475, 11905.50587488394, 11922.105842062054, 12040.0, 12262.325572093621, 13110.082294239312, 14288.42846286137, 14556.962025316523, 14620.778512847533, 14632.268386580841, 14705.70588588201, 12704.6823829405, 12899.93550032244, 13042.869571304165, 13091.279979601213, 13342.19946803195, 11412.657620271406, 11633.800704805908, 11659.18385712992, 12151.331676757864, 12211.511539538318, 11795.233309834834, 12272.570460033856, 12421.689457763629, 12583.433745481658, 12720.0, 10701.0, 11029.724256893322, 11183.32900026001, 11208.10335173168, 11373.943130284295, 10267.84598231042, 10718.785624287315, 10769.223078192324, 10901.727456813644, 10993.725156871142], "value": 6929.848145148122, "unit": "score"}], "name": "dromaeo_css", "value": 6991.265146766401, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 111.0, 112.0, 113.0, 112.0, 112.0, 114.0, 111.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 120.0, 156.0, 121.0, 126.0, 120.0, 118.0, 141.0, 120.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 110.0, 76.0, 75.0, 75.0, 75.0, 92.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 111.0, 83.0, 83.0, 83.0, 104.0, 83.0, 83.0, 106.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 128.0, 127.0, 127.0, 127.0, 127.0, 126.0], "value": 127.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 63.0, 63.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 90.0, 84.0, 81.0, 93.0, 80.0, 80.0, 83.0, 80.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 140.0, 144.0, 130.0, 128.0, 126.0, 125.0, 163.0, 142.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 168.0, 165.0, 165.0, 169.0, 163.0, 172.0, 164.0, 164.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 63.0, 63.0, 62.0, 62.0, 64.0, 63.0, 61.0, 64.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1505.4999999999998, "alertThreshold": 2.0}]} 09:28:12 INFO - Return code: 0 09:28:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:28:12 INFO - # TBPL SUCCESS # 09:28:12 INFO - Running post-action listener: _resource_record_post_action 09:28:12 INFO - [mozharness: 2016-12-18 17:28:12.199060Z] Finished run-tests step (success) 09:28:12 INFO - Running post-run listener: _resource_record_post_run 09:28:12 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395599872; Read time: 4; Write time: 791672 09:28:12 INFO - TinderboxPrint: CPU usage
13.2% 09:28:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 09:28:12 INFO - TinderboxPrint: I/O write bytes / time
395,599,872 / 791,672 09:28:12 INFO - TinderboxPrint: CPU idle
8,299.5 (86.8%) 09:28:12 INFO - TinderboxPrint: CPU user
1,206.8 (12.6%) 09:28:12 INFO - TinderboxPrint: Swap in / out
0 / 0 09:28:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104595456; Read time: 0; Write time: 207564 09:28:12 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 291004416; Read time: 0; Write time: 584108 09:28:12 INFO - Running post-run listener: _upload_blobber_files 09:28:12 INFO - Blob upload gear active. 09:28:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:12 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 09:28:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:28:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:28:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:28:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:14 INFO - (blobuploader) - INFO - Done attempting. 09:28:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:28:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:28:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:17 INFO - (blobuploader) - INFO - Done attempting. 09:28:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:18 INFO - (blobuploader) - INFO - Done attempting. 09:28:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:21 INFO - (blobuploader) - INFO - Done attempting. 09:28:21 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:21 INFO - Return code: 0 09:28:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8e9931a64146944f572be8d73c365c8ee157ff2e9284681f0d681bff3f18370855a3dbb7c11e91627cacad3d05b43c10b2411b66b26782123fee3f99a094245", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222e1b93f995ccd98c7f62278f46c91ebeaf5905224d41ada67364f5ab05855c08d85e53897c3a6637f29236f26fbd7d02f193e69f86ec8479e19c27caba4896", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ce08d9ae62ec16b624df9adc44a29a209371be2e4e62fb079a949bf9c86ca332e7050fb26c37354edf6817658c214ba6938b0351bbd366be64bffd032400847", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f502197a9d482accdade023852a2df4146cd781a487a4caf76ebaa587c09aab1a02de8a18f23bea64a167a360922191ae2e87f8ed79012790c5103e4bd8d8b0"} 09:28:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:21 INFO - Contents: 09:28:21 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8e9931a64146944f572be8d73c365c8ee157ff2e9284681f0d681bff3f18370855a3dbb7c11e91627cacad3d05b43c10b2411b66b26782123fee3f99a094245", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222e1b93f995ccd98c7f62278f46c91ebeaf5905224d41ada67364f5ab05855c08d85e53897c3a6637f29236f26fbd7d02f193e69f86ec8479e19c27caba4896", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ce08d9ae62ec16b624df9adc44a29a209371be2e4e62fb079a949bf9c86ca332e7050fb26c37354edf6817658c214ba6938b0351bbd366be64bffd032400847", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f502197a9d482accdade023852a2df4146cd781a487a4caf76ebaa587c09aab1a02de8a18f23bea64a167a360922191ae2e87f8ed79012790c5103e4bd8d8b0"} 09:28:21 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:21 INFO - Copying logs to upload dir... 09:28:21 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.989255 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-18 09:28:21.390482) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 09:28:21.395289) ========= 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=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8e9931a64146944f572be8d73c365c8ee157ff2e9284681f0d681bff3f18370855a3dbb7c11e91627cacad3d05b43c10b2411b66b26782123fee3f99a094245", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222e1b93f995ccd98c7f62278f46c91ebeaf5905224d41ada67364f5ab05855c08d85e53897c3a6637f29236f26fbd7d02f193e69f86ec8479e19c27caba4896", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ce08d9ae62ec16b624df9adc44a29a209371be2e4e62fb079a949bf9c86ca332e7050fb26c37354edf6817658c214ba6938b0351bbd366be64bffd032400847", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f502197a9d482accdade023852a2df4146cd781a487a4caf76ebaa587c09aab1a02de8a18f23bea64a167a360922191ae2e87f8ed79012790c5103e4bd8d8b0"} build_url:https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/DED5MOpnRNOslMQjpxLHkw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8e9931a64146944f572be8d73c365c8ee157ff2e9284681f0d681bff3f18370855a3dbb7c11e91627cacad3d05b43c10b2411b66b26782123fee3f99a094245", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/222e1b93f995ccd98c7f62278f46c91ebeaf5905224d41ada67364f5ab05855c08d85e53897c3a6637f29236f26fbd7d02f193e69f86ec8479e19c27caba4896", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ce08d9ae62ec16b624df9adc44a29a209371be2e4e62fb079a949bf9c86ca332e7050fb26c37354edf6817658c214ba6938b0351bbd366be64bffd032400847", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f502197a9d482accdade023852a2df4146cd781a487a4caf76ebaa587c09aab1a02de8a18f23bea64a167a360922191ae2e87f8ed79012790c5103e4bd8d8b0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 09:28:21.423975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:28:21.424363) ========= 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=df17445c3590ff7897617a0e00000225-1482079075.636294-2132567141 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004619 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 09:28:21.476943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 09:28:21.477317) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 09:28:21.477671) ========= ========= Total master_lag: 0.18 =========