builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1482411781.47 results: success (0) buildid: 20161222030653 builduid: 1d50fdaf47ef471ba580337b65ab94b0 revision: 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.466677) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.467127) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.467409) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.485188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.485443) ========= 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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 05:03:01-- 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% 233M=0s 2016-12-22 05:03:01 (233 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104377 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.603230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.603528) ========= 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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021651 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.653893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:01.654244) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 --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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 05:03:01,795 truncating revision to first 12 chars 2016-12-22 05:03:01,795 Setting DEBUG logging. 2016-12-22 05:03:01,795 attempt 1/10 2016-12-22 05:03:01,795 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8ab2c27f4cc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 05:03:02,260 unpacking tar archive at: mozilla-beta-8ab2c27f4cc0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.771688 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:02.438907) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:02.439192) ========= script_repo_revision: 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:02.439556) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:02.439820) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 05:03:02.450529) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-12-22 05:03:02.450804) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:03:02 INFO - MultiFileLogger online at 20161222 05:03:02 in /builds/slave/test 05:03:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:03:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:02 INFO - {'append_to_log': False, 05:03:02 INFO - 'base_work_dir': '/builds/slave/test', 05:03:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:03:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:02 INFO - 'branch': 'Mozilla-Beta', 05:03:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:02 INFO - 'config_files': ('talos/linux_config.py',), 05:03:02 INFO - 'default_actions': ('clobber', 05:03:02 INFO - 'read-buildbot-config', 05:03:02 INFO - 'download-and-extract', 05:03:02 INFO - 'populate-webroot', 05:03:02 INFO - 'create-virtualenv', 05:03:02 INFO - 'install', 05:03:02 INFO - 'run-tests'), 05:03:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:02 INFO - 'download_minidump_stackwalk': True, 05:03:02 INFO - 'download_symbols': 'ondemand', 05:03:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:02 INFO - 'installer_path': 'installer.exe', 05:03:02 INFO - 'log_level': 'info', 05:03:02 INFO - 'log_name': 'talos', 05:03:02 INFO - 'log_to_console': True, 05:03:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:03:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:03:02 INFO - 'opt_config_files': (), 05:03:02 INFO - 'pip_index': False, 05:03:02 INFO - 'sps_profile': False, 05:03:02 INFO - 'sps_profile_interval': 0, 05:03:02 INFO - 'suite': 'dromaeojs', 05:03:02 INFO - 'system_bits': '32', 05:03:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:02 INFO - 'title': 'talos-linux64-ix-017', 05:03:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:02 INFO - 'use_talos_json': True, 05:03:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:02 INFO - 'work_dir': 'build'} 05:03:02 INFO - [mozharness: 2016-12-22 13:03:02.687442Z] Running clobber step. 05:03:02 INFO - Running pre-action listener: _resource_record_pre_action 05:03:02 INFO - Running main action method: clobber 05:03:02 INFO - rmtree: /builds/slave/test/build 05:03:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:03:03 INFO - Running post-action listener: _resource_record_post_action 05:03:03 INFO - [mozharness: 2016-12-22 13:03:03.656457Z] Finished clobber step (success) 05:03:03 INFO - [mozharness: 2016-12-22 13:03:03.656556Z] Running read-buildbot-config step. 05:03:03 INFO - Running pre-action listener: _resource_record_pre_action 05:03:03 INFO - Running main action method: read_buildbot_config 05:03:03 INFO - Using buildbot properties: 05:03:03 INFO - { 05:03:03 INFO - "project": "", 05:03:03 INFO - "product": "firefox", 05:03:03 INFO - "script_repo_revision": "production", 05:03:03 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:03:03 INFO - "repository": "", 05:03:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:03:03 INFO - "buildid": "20161222030653", 05:03:03 INFO - "pgo_build": "True", 05:03:03 INFO - "basedir": "/builds/slave/test", 05:03:03 INFO - "buildnumber": 33, 05:03:03 INFO - "slavename": "talos-linux64-ix-017", 05:03:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:03:03 INFO - "platform": "ubuntu64_hw", 05:03:03 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:03:03 INFO - "branch": "mozilla-beta", 05:03:03 INFO - "repo_path": "releases/mozilla-beta", 05:03:03 INFO - "revision": "8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7", 05:03:03 INFO - "stage_platform": "linux64-pgo", 05:03:03 INFO - "builduid": "1d50fdaf47ef471ba580337b65ab94b0", 05:03:03 INFO - "slavebuilddir": "test" 05:03:03 INFO - } 05:03:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 05:03:03 INFO - Running post-action listener: _resource_record_post_action 05:03:03 INFO - [mozharness: 2016-12-22 13:03:03.658998Z] Finished read-buildbot-config step (success) 05:03:03 INFO - [mozharness: 2016-12-22 13:03:03.659087Z] Running download-and-extract step. 05:03:03 INFO - Running pre-action listener: _resource_record_pre_action 05:03:03 INFO - Running main action method: download_and_extract 05:03:03 INFO - mkdir: /builds/slave/test/build/tests 05:03:03 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')]} 05:03:03 INFO - trying https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:03:03 INFO - Downloading https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:03:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:03:04 INFO - Downloaded 1327 bytes. 05:03:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 05:03:04 INFO - Using the following test package requirements: 05:03:04 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 05:03:04 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:03:04 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 05:03:04 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'jsshell-linux-x86_64.zip'], 05:03:04 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:03:04 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 05:03:04 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 05:03:04 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 05:03:04 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:03:04 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 05:03:04 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:03:04 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:03:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 05:03:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:03:04 INFO - Fetch https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 05:03:04 INFO - Expected file size: 21963687 05:03:09 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:03:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 05:03:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:03:09 INFO - Fetch https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 05:03:09 INFO - Expected file size: 21963687 05:03:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 05:03:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:03:14 INFO - Fetch https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 05:03:14 INFO - Expected file size: 14414089 05:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:15 INFO - trying https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:03:15 INFO - Downloading https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:03:16 INFO - Downloaded 56950160 bytes. 05:03:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:03:16 INFO - mkdir: /builds/slave/test/properties 05:03:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:03:16 INFO - Writing to file /builds/slave/test/properties/build_url 05:03:16 INFO - Contents: 05:03:16 INFO - build_url:https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 05:03:17 INFO - Running post-action listener: _resource_record_post_action 05:03:17 INFO - Running post-action listener: set_extra_try_arguments 05:03:17 INFO - [mozharness: 2016-12-22 13:03:17.164097Z] Finished download-and-extract step (success) 05:03:17 INFO - [mozharness: 2016-12-22 13:03:17.164324Z] Running populate-webroot step. 05:03:17 INFO - Running pre-action listener: _resource_record_pre_action 05:03:17 INFO - Running main action method: populate_webroot 05:03:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:03:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:03:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:03:17 INFO - '--tppagecycles', 05:03:17 INFO - '7'], 05:03:17 INFO - 'tests': ['tsvgm']}}, 05:03:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:03:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:03:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:03:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:03:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:03:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:03:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['damp', 'tps']}, 05:03:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'tests': ['damp', 'tps']}, 05:03:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['dromaeo_dom']}, 05:03:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:03:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['basic_compositor_video']}, 05:03:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:03:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['a11yr', 05:03:17 INFO - 'ts_paint', 05:03:17 INFO - 'tpaint', 05:03:17 INFO - 'sessionrestore', 05:03:17 INFO - 'sessionrestore_no_auto_restore', 05:03:17 INFO - 'tabpaint']}, 05:03:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['tsvgx', 05:03:17 INFO - 'tsvgr_opacity', 05:03:17 INFO - 'tart', 05:03:17 INFO - 'tscrollx', 05:03:17 INFO - 'cart']}, 05:03:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:03:17 INFO - 'tsvgr_opacity', 05:03:17 INFO - 'tart', 05:03:17 INFO - 'tscrollx', 05:03:17 INFO - 'cart']}, 05:03:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'talos_options': ['--disable-e10s'], 05:03:17 INFO - 'tests': ['tp5o']}, 05:03:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'tests': ['tp5o']}, 05:03:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'talos_options': ['--disable-e10s', 05:03:17 INFO - '--xperf_path', 05:03:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:03:17 INFO - 'tests': ['tp5n']}, 05:03:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:03:17 INFO - 'talos_options': ['--xperf_path', 05:03:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:03:17 INFO - 'tests': ['tp5n']}}, 05:03:17 INFO - 'talos.zip': {'path': '', 05:03:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:03:17 INFO - Running post-action listener: _resource_record_post_action 05:03:17 INFO - [mozharness: 2016-12-22 13:03:17.180856Z] Finished populate-webroot step (success) 05:03:17 INFO - [mozharness: 2016-12-22 13:03:17.180950Z] Running create-virtualenv step. 05:03:17 INFO - Running pre-action listener: _resource_record_pre_action 05:03:17 INFO - Running main action method: create_virtualenv 05:03:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:03:17 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 05:03:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:03:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:03:17 INFO - Using real prefix '/usr' 05:03:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:03:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:03:19 INFO - Installing pip.................done. 05:03:19 INFO - Return code: 0 05:03:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:03:19 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')]} 05:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:19 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')]} 05:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:19 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:19 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 05:03:19 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 05:03:19 INFO - Using env: {'DISPLAY': ':0', 05:03:19 INFO - 'HOME': '/home/cltbld', 05:03:19 INFO - 'LANG': 'en_US.UTF-8', 05:03:19 INFO - 'LANGUAGE': 'en_US:en', 05:03:19 INFO - 'LOGNAME': 'cltbld', 05:03:19 INFO - 'MAIL': '/var/mail/cltbld', 05:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:19 INFO - 'MOZ_NO_REMOTE': '1', 05:03:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:19 INFO - 'NO_EM_RESTART': '1', 05:03:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:19 INFO - 'PWD': '/builds/slave/test', 05:03:19 INFO - 'SHELL': '/bin/bash', 05:03:19 INFO - 'SHLVL': '1', 05:03:19 INFO - 'TERM': 'linux', 05:03:19 INFO - 'TMOUT': '86400', 05:03:19 INFO - 'USER': 'cltbld', 05:03:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:19 INFO - '_': '/tools/buildbot/bin/python'} 05:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:19 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 05:03:19 INFO - Cleaning up... 05:03:19 INFO - Return code: 0 05:03:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:03:19 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')]} 05:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:19 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')]} 05:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:19 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:19 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 05:03:19 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 05:03:19 INFO - Using env: {'DISPLAY': ':0', 05:03:19 INFO - 'HOME': '/home/cltbld', 05:03:19 INFO - 'LANG': 'en_US.UTF-8', 05:03:19 INFO - 'LANGUAGE': 'en_US:en', 05:03:19 INFO - 'LOGNAME': 'cltbld', 05:03:19 INFO - 'MAIL': '/var/mail/cltbld', 05:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:19 INFO - 'MOZ_NO_REMOTE': '1', 05:03:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:19 INFO - 'NO_EM_RESTART': '1', 05:03:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:19 INFO - 'PWD': '/builds/slave/test', 05:03:19 INFO - 'SHELL': '/bin/bash', 05:03:19 INFO - 'SHLVL': '1', 05:03:19 INFO - 'TERM': 'linux', 05:03:19 INFO - 'TMOUT': '86400', 05:03:19 INFO - 'USER': 'cltbld', 05:03:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:19 INFO - '_': '/tools/buildbot/bin/python'} 05:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:20 INFO - Downloading/unpacking psutil>=3.1.1 05:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:03:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:22 INFO - Installing collected packages: psutil 05:03:22 INFO - Running setup.py install for psutil 05:03:22 INFO - building 'psutil._psutil_linux' extension 05:03:22 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 05:03:22 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 05:03:22 INFO - building 'psutil._psutil_posix' extension 05:03:22 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 05:03:22 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 05:03:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:22 INFO - Successfully installed psutil 05:03:22 INFO - Cleaning up... 05:03:22 INFO - Return code: 0 05:03:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:03:22 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')]} 05:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:22 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')]} 05:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:22 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:22 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 05:03:22 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 05:03:22 INFO - Using env: {'DISPLAY': ':0', 05:03:22 INFO - 'HOME': '/home/cltbld', 05:03:22 INFO - 'LANG': 'en_US.UTF-8', 05:03:22 INFO - 'LANGUAGE': 'en_US:en', 05:03:22 INFO - 'LOGNAME': 'cltbld', 05:03:22 INFO - 'MAIL': '/var/mail/cltbld', 05:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:22 INFO - 'MOZ_NO_REMOTE': '1', 05:03:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:22 INFO - 'NO_EM_RESTART': '1', 05:03:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:22 INFO - 'PWD': '/builds/slave/test', 05:03:22 INFO - 'SHELL': '/bin/bash', 05:03:22 INFO - 'SHLVL': '1', 05:03:22 INFO - 'TERM': 'linux', 05:03:22 INFO - 'TMOUT': '86400', 05:03:22 INFO - 'USER': 'cltbld', 05:03:22 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:22 INFO - '_': '/tools/buildbot/bin/python'} 05:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:03:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:03:25 INFO - Installing collected packages: mozsystemmonitor 05:03:25 INFO - Running setup.py install for mozsystemmonitor 05:03:25 INFO - Successfully installed mozsystemmonitor 05:03:25 INFO - Cleaning up... 05:03:25 INFO - Return code: 0 05:03:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:03:25 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')]} 05:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:25 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')]} 05:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:25 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:25 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 05:03:25 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 05:03:25 INFO - Using env: {'DISPLAY': ':0', 05:03:25 INFO - 'HOME': '/home/cltbld', 05:03:25 INFO - 'LANG': 'en_US.UTF-8', 05:03:25 INFO - 'LANGUAGE': 'en_US:en', 05:03:25 INFO - 'LOGNAME': 'cltbld', 05:03:25 INFO - 'MAIL': '/var/mail/cltbld', 05:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:25 INFO - 'MOZ_NO_REMOTE': '1', 05:03:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:25 INFO - 'NO_EM_RESTART': '1', 05:03:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:25 INFO - 'PWD': '/builds/slave/test', 05:03:25 INFO - 'SHELL': '/bin/bash', 05:03:25 INFO - 'SHLVL': '1', 05:03:25 INFO - 'TERM': 'linux', 05:03:25 INFO - 'TMOUT': '86400', 05:03:25 INFO - 'USER': 'cltbld', 05:03:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:25 INFO - '_': '/tools/buildbot/bin/python'} 05:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:25 INFO - Downloading/unpacking jsonschema==2.5.1 05:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:03:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:03:27 INFO - warning: no files found matching '*.txt' 05:03:27 INFO - no previously-included directories found matching 'build' 05:03:27 INFO - no previously-included directories found matching 'dist' 05:03:27 INFO - no previously-included directories found matching '.git*' 05:03:27 INFO - Installing collected packages: jsonschema, functools32 05:03:27 INFO - Running setup.py install for functools32 05:03:27 INFO - warning: no files found matching '*.txt' 05:03:27 INFO - no previously-included directories found matching 'build' 05:03:27 INFO - no previously-included directories found matching 'dist' 05:03:27 INFO - no previously-included directories found matching '.git*' 05:03:27 INFO - Successfully installed jsonschema functools32 05:03:27 INFO - Cleaning up... 05:03:28 INFO - Return code: 0 05:03:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:03:28 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')]} 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:28 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')]} 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:28 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:28 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 05:03:28 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 05:03:28 INFO - Using env: {'DISPLAY': ':0', 05:03:28 INFO - 'HOME': '/home/cltbld', 05:03:28 INFO - 'LANG': 'en_US.UTF-8', 05:03:28 INFO - 'LANGUAGE': 'en_US:en', 05:03:28 INFO - 'LOGNAME': 'cltbld', 05:03:28 INFO - 'MAIL': '/var/mail/cltbld', 05:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:28 INFO - 'MOZ_NO_REMOTE': '1', 05:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:28 INFO - 'NO_EM_RESTART': '1', 05:03:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:28 INFO - 'PWD': '/builds/slave/test', 05:03:28 INFO - 'SHELL': '/bin/bash', 05:03:28 INFO - 'SHLVL': '1', 05:03:28 INFO - 'TERM': 'linux', 05:03:28 INFO - 'TMOUT': '86400', 05:03:28 INFO - 'USER': 'cltbld', 05:03:28 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:28 INFO - '_': '/tools/buildbot/bin/python'} 05:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:03:28 INFO - Cleaning up... 05:03:28 INFO - Return code: 0 05:03:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:03:28 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')]} 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:28 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')]} 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:28 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:28 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 05:03:28 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 05:03:28 INFO - Using env: {'DISPLAY': ':0', 05:03:28 INFO - 'HOME': '/home/cltbld', 05:03:28 INFO - 'LANG': 'en_US.UTF-8', 05:03:28 INFO - 'LANGUAGE': 'en_US:en', 05:03:28 INFO - 'LOGNAME': 'cltbld', 05:03:28 INFO - 'MAIL': '/var/mail/cltbld', 05:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:28 INFO - 'MOZ_NO_REMOTE': '1', 05:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:28 INFO - 'NO_EM_RESTART': '1', 05:03:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:28 INFO - 'PWD': '/builds/slave/test', 05:03:28 INFO - 'SHELL': '/bin/bash', 05:03:28 INFO - 'SHLVL': '1', 05:03:28 INFO - 'TERM': 'linux', 05:03:28 INFO - 'TMOUT': '86400', 05:03:28 INFO - 'USER': 'cltbld', 05:03:28 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:28 INFO - '_': '/tools/buildbot/bin/python'} 05:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:03:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:03:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:31 INFO - Downloading docopt-0.6.1.tar.gz 05:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:03:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:03:31 INFO - Running setup.py install for blobuploader 05:03:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:03:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:03:31 INFO - Running setup.py install for requests 05:03:31 INFO - Running setup.py install for docopt 05:03:31 INFO - Successfully installed blobuploader requests docopt 05:03:31 INFO - Cleaning up... 05:03:31 INFO - Return code: 0 05:03:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03: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')]} 05:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03: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')]} 05:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:31 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:31 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 05:03:31 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 05:03:31 INFO - Using env: {'DISPLAY': ':0', 05:03:31 INFO - 'HOME': '/home/cltbld', 05:03:31 INFO - 'LANG': 'en_US.UTF-8', 05:03:31 INFO - 'LANGUAGE': 'en_US:en', 05:03:31 INFO - 'LOGNAME': 'cltbld', 05:03:31 INFO - 'MAIL': '/var/mail/cltbld', 05:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:31 INFO - 'MOZ_NO_REMOTE': '1', 05:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:31 INFO - 'NO_EM_RESTART': '1', 05:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:31 INFO - 'PWD': '/builds/slave/test', 05:03:31 INFO - 'SHELL': '/bin/bash', 05:03:31 INFO - 'SHLVL': '1', 05:03:31 INFO - 'TERM': 'linux', 05:03:31 INFO - 'TMOUT': '86400', 05:03:31 INFO - 'USER': 'cltbld', 05:03:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:31 INFO - '_': '/tools/buildbot/bin/python'} 05:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:32 INFO - Running setup.py (path:/tmp/pip-AnauLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:32 INFO - Running setup.py (path:/tmp/pip-Rq3VkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:32 INFO - Running setup.py (path:/tmp/pip-1mSteM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:32 INFO - Running setup.py (path:/tmp/pip-Wn07lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:32 INFO - Running setup.py (path:/tmp/pip-o9HqXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:32 INFO - Running setup.py (path:/tmp/pip-fMR8ZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:32 INFO - Running setup.py (path:/tmp/pip-6_2PGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:32 INFO - Running setup.py (path:/tmp/pip-OONDCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:32 INFO - Running setup.py (path:/tmp/pip-zgOxoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:32 INFO - Running setup.py (path:/tmp/pip-bNQpHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:32 INFO - Running setup.py (path:/tmp/pip-kW2Oyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:32 INFO - Running setup.py (path:/tmp/pip-p8JHgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:33 INFO - Running setup.py (path:/tmp/pip-T8FU6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:33 INFO - Running setup.py (path:/tmp/pip-vJXw3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:33 INFO - Running setup.py (path:/tmp/pip-YmJdGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:33 INFO - Running setup.py (path:/tmp/pip-o4g3L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:33 INFO - Running setup.py (path:/tmp/pip-59jrdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:03:33 INFO - Running setup.py install for manifestparser 05:03:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:03:33 INFO - Running setup.py install for mozcrash 05:03:33 INFO - Running setup.py install for mozdebug 05:03:33 INFO - Running setup.py install for mozdevice 05:03:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:03:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:03:33 INFO - Running setup.py install for mozfile 05:03:33 INFO - Running setup.py install for mozhttpd 05:03:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:03:33 INFO - Running setup.py install for mozinfo 05:03:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for mozInstall 05:03:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for mozleak 05:03:34 INFO - Running setup.py install for mozlog 05:03:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for moznetwork 05:03:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for mozprocess 05:03:34 INFO - Running setup.py install for mozprofile 05:03:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for mozrunner 05:03:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:03:34 INFO - Running setup.py install for mozscreenshot 05:03:34 INFO - Running setup.py install for moztest 05:03:35 INFO - Running setup.py install for mozversion 05:03:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:03:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:03:35 INFO - Cleaning up... 05:03:35 INFO - Return code: 0 05:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:35 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:35 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 05:03:35 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 05:03:35 INFO - Using env: {'DISPLAY': ':0', 05:03:35 INFO - 'HOME': '/home/cltbld', 05:03:35 INFO - 'LANG': 'en_US.UTF-8', 05:03:35 INFO - 'LANGUAGE': 'en_US:en', 05:03:35 INFO - 'LOGNAME': 'cltbld', 05:03:35 INFO - 'MAIL': '/var/mail/cltbld', 05:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:35 INFO - 'MOZ_NO_REMOTE': '1', 05:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:35 INFO - 'NO_EM_RESTART': '1', 05:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:35 INFO - 'PWD': '/builds/slave/test', 05:03:35 INFO - 'SHELL': '/bin/bash', 05:03:35 INFO - 'SHLVL': '1', 05:03:35 INFO - 'TERM': 'linux', 05:03:35 INFO - 'TMOUT': '86400', 05:03:35 INFO - 'USER': 'cltbld', 05:03:35 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:35 INFO - '_': '/tools/buildbot/bin/python'} 05:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:35 INFO - Running setup.py (path:/tmp/pip-hj1lPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:35 INFO - Running setup.py (path:/tmp/pip-E4FMsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:35 INFO - Running setup.py (path:/tmp/pip-cSXYCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:35 INFO - Running setup.py (path:/tmp/pip-GmTLUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:35 INFO - Running setup.py (path:/tmp/pip-3pr8Im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:35 INFO - Running setup.py (path:/tmp/pip-Dhc8bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:35 INFO - Running setup.py (path:/tmp/pip-WMRSuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:35 INFO - Running setup.py (path:/tmp/pip-jexyKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:35 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)) 05:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:35 INFO - Running setup.py (path:/tmp/pip-E9gyMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:36 INFO - Running setup.py (path:/tmp/pip-oliYZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:36 INFO - Running setup.py (path:/tmp/pip-MexqFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:36 INFO - Running setup.py (path:/tmp/pip-tOqJE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:36 INFO - Running setup.py (path:/tmp/pip-WJZ6uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:36 INFO - Running setup.py (path:/tmp/pip-BYjPVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:36 INFO - Running setup.py (path:/tmp/pip-rENS46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:36 INFO - Running setup.py (path:/tmp/pip-sDmyLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:36 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)) 05:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:36 INFO - Running setup.py (path:/tmp/pip-RC4hxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:36 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)) 05:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:03:36 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)) 05:03:36 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:03:36 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:03:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:03:36 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 05:03:36 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 05:03:36 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 05:03:36 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 05:03:36 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 05:03:36 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 05:03:38 INFO - Downloading blessings-1.6.tar.gz 05:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:03:38 INFO - Installing collected packages: blessings 05:03:38 INFO - Running setup.py install for blessings 05:03:38 INFO - Successfully installed blessings 05:03:38 INFO - Cleaning up... 05:03:38 INFO - Return code: 0 05:03:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:03:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:39 INFO - Reading from file tmpfile_stdout 05:03:39 INFO - Current package versions: 05:03:39 INFO - argparse == 1.2.1 05:03:39 INFO - blessings == 1.6 05:03:39 INFO - blobuploader == 1.2.4 05:03:39 INFO - docopt == 0.6.1 05:03:39 INFO - functools32 == 3.2.3-2 05:03:39 INFO - jsonschema == 2.5.1 05:03:39 INFO - manifestparser == 1.1 05:03:39 INFO - mozInstall == 1.12 05:03:39 INFO - mozcrash == 0.17 05:03:39 INFO - mozdebug == 0.1 05:03:39 INFO - mozdevice == 0.48 05:03:39 INFO - mozfile == 1.2 05:03:39 INFO - mozhttpd == 0.7 05:03:39 INFO - mozinfo == 0.9 05:03:39 INFO - mozleak == 0.1 05:03:39 INFO - mozlog == 3.4 05:03:39 INFO - moznetwork == 0.27 05:03:39 INFO - mozprocess == 0.23 05:03:39 INFO - mozprofile == 0.28 05:03:39 INFO - mozrunner == 6.12 05:03:39 INFO - mozscreenshot == 0.1 05:03:39 INFO - mozsystemmonitor == 0.3 05:03:39 INFO - moztest == 0.7 05:03:39 INFO - mozversion == 1.4 05:03:39 INFO - psutil == 3.1.1 05:03:39 INFO - requests == 1.2.3 05:03:39 INFO - wsgiref == 0.1.2 05:03:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:39 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')]} 05:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:39 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')]} 05:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:39 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:39 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 05:03:39 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 05:03:39 INFO - Using env: {'DISPLAY': ':0', 05:03:39 INFO - 'HOME': '/home/cltbld', 05:03:39 INFO - 'LANG': 'en_US.UTF-8', 05:03:39 INFO - 'LANGUAGE': 'en_US:en', 05:03:39 INFO - 'LOGNAME': 'cltbld', 05:03:39 INFO - 'MAIL': '/var/mail/cltbld', 05:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:39 INFO - 'MOZ_NO_REMOTE': '1', 05:03:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:39 INFO - 'NO_EM_RESTART': '1', 05:03:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:39 INFO - 'PWD': '/builds/slave/test', 05:03:39 INFO - 'SHELL': '/bin/bash', 05:03:39 INFO - 'SHLVL': '1', 05:03:39 INFO - 'TERM': 'linux', 05:03:39 INFO - 'TMOUT': '86400', 05:03:39 INFO - 'USER': 'cltbld', 05:03:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:39 INFO - '_': '/tools/buildbot/bin/python'} 05:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 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)) 05:03:39 INFO - Cleaning up... 05:03:39 INFO - Return code: 0 05:03:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:03:39 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')]} 05:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:39 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')]} 05:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:03:39 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 0x7ffd42ca9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, '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': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', '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 05:03:39 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 05:03:39 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 05:03:39 INFO - Using env: {'DISPLAY': ':0', 05:03:39 INFO - 'HOME': '/home/cltbld', 05:03:39 INFO - 'LANG': 'en_US.UTF-8', 05:03:39 INFO - 'LANGUAGE': 'en_US:en', 05:03:39 INFO - 'LOGNAME': 'cltbld', 05:03:39 INFO - 'MAIL': '/var/mail/cltbld', 05:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:39 INFO - 'MOZ_NO_REMOTE': '1', 05:03:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:39 INFO - 'NO_EM_RESTART': '1', 05:03:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:39 INFO - 'PWD': '/builds/slave/test', 05:03:39 INFO - 'SHELL': '/bin/bash', 05:03:39 INFO - 'SHLVL': '1', 05:03:39 INFO - 'TERM': 'linux', 05:03:39 INFO - 'TMOUT': '86400', 05:03:39 INFO - 'USER': 'cltbld', 05:03:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:39 INFO - '_': '/tools/buildbot/bin/python'} 05:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:03:39 INFO - Cleaning up... 05:03:39 INFO - Return code: 0 05:03:39 INFO - Running post-action listener: _resource_record_post_action 05:03:39 INFO - Running post-action listener: _start_resource_monitoring 05:03:39 INFO - Starting resource monitoring. 05:03:39 INFO - [mozharness: 2016-12-22 13:03:39.580852Z] Finished create-virtualenv step (success) 05:03:39 INFO - [mozharness: 2016-12-22 13:03:39.581364Z] Running install step. 05:03:39 INFO - Running pre-action listener: _resource_record_pre_action 05:03:39 INFO - Running main action method: install 05:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:39 INFO - Reading from file tmpfile_stdout 05:03:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:03:39 INFO - Reading from file tmpfile_stdout 05:03:39 INFO - Output received: 05:03:39 INFO - Usage: mozinstall [options] installer 05:03:39 INFO - Options: 05:03:39 INFO - -h, --help show this help message and exit 05:03:39 INFO - -d DEST, --destination=DEST 05:03:39 INFO - Directory to install application into. [default: 05:03:39 INFO - "/builds/slave/test"] 05:03:39 INFO - --app=APP Application being installed. [default: firefox] 05:03:39 INFO - mkdir: /builds/slave/test/build/application 05:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:03:53 INFO - Reading from file tmpfile_stdout 05:03:53 INFO - Output received: 05:03:53 INFO - /builds/slave/test/build/application/firefox/firefox 05:03:53 INFO - Running post-action listener: _resource_record_post_action 05:03:53 INFO - [mozharness: 2016-12-22 13:03:53.358866Z] Finished install step (success) 05:03:53 INFO - [mozharness: 2016-12-22 13:03:53.359109Z] Running run-tests step. 05:03:53 INFO - Running pre-action listener: _resource_record_pre_action 05:03:53 INFO - Running main action method: run_tests 05:03:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:03:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:03:53 INFO - Python 2.7.3 05:03:53 INFO - Return code: 0 05:03:53 INFO - grabbing minidump binary from tooltool 05:03: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')]} 05:03:53 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 0x17eff60>, '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 0x17f0d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f11d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:03:53 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 05:03:53 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 05:03:53 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 05:03:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:03:53 INFO - Return code: 0 05:03:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:03:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:03:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:03:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:03:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:03:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 05:03:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 05:03:53 INFO - Using env: {'DISPLAY': ':0', 05:03:53 INFO - 'HOME': '/home/cltbld', 05:03:53 INFO - 'LANG': 'en_US.UTF-8', 05:03:53 INFO - 'LANGUAGE': 'en_US:en', 05:03:53 INFO - 'LOGNAME': 'cltbld', 05:03:53 INFO - 'MAIL': '/var/mail/cltbld', 05:03:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:03:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:53 INFO - 'MOZ_NO_REMOTE': '1', 05:03:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:03:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:03:53 INFO - 'NO_EM_RESTART': '1', 05:03:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:53 INFO - 'PWD': '/builds/slave/test', 05:03:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:03:53 INFO - 'SHELL': '/bin/bash', 05:03:53 INFO - 'SHLVL': '1', 05:03:53 INFO - 'TERM': 'linux', 05:03:53 INFO - 'TMOUT': '86400', 05:03:53 INFO - 'USER': 'cltbld', 05:03:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821', 05:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:53 INFO - '_': '/tools/buildbot/bin/python'} 05:03:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 05:03:53 INFO - mozversion application_buildid: 20161222030653 05:03:53 INFO - mozversion application_changeset: 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 05:03:53 INFO - mozversion application_display_name: Firefox 05:03:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:03:53 INFO - mozversion application_name: Firefox 05:03:53 INFO - mozversion application_remotingname: firefox 05:03:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:03:53 INFO - mozversion application_vendor: Mozilla 05:03:53 INFO - mozversion application_version: 51.0 05:03:53 INFO - mozversion platform_buildid: 20161222030653 05:03:53 INFO - mozversion platform_changeset: 8ab2c27f4cc096922e6db9bc4cc083c4f3dac4f7 05:03:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:03:53 INFO - mozversion platform_version: 51.0 05:03:53 INFO - using testdate: 1482411833 05:03:53 INFO - actual date: 1482411833 05:03:53 INFO - starting webserver on 'localhost:41938' 05:03:53 INFO - SUITE-START | Running 2 tests 05:03:53 INFO - TEST-START | dromaeo_css 05:03:53 INFO - operating with platform_type : linux_ 05:03:53 INFO - Initialising browser for dromaeo_css test... 05:03:53 INFO - TEST-INFO | started process 18661 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UdYj7/profile http://localhost:41938/getInfo.html) 05:03:57 INFO - PROCESS | 18661 | __metrics Screen width/height:1600/1200 05:03:57 INFO - PROCESS | 18661 | colorDepth:24 05:03:57 INFO - PROCESS | 18661 | Browser inner width/height: 1024/697 05:03:57 INFO - PROCESS | 18661 | __metrics 05:03:57 INFO - PROCESS | 18661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:03:57 INFO - PROCESS | 18661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:03:58 INFO - PROCESS | 18661 | console.error: PushService: 05:03:58 INFO - PROCESS | 18661 | stateChangeProcessEnqueue: Error transitioning state 05:03:58 INFO - PROCESS | 18661 | UnknownError 05:03:58 INFO - TEST-INFO | 18661: exit 0 05:03:58 INFO - Browser initialized. 05:03:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:58 INFO - TEST-INFO | started process 18754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UdYj7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:10 INFO - PROCESS | 18754 | RSS: Main: 142938112 05:04:10 INFO - PROCESS | 18754 | 05:05:01 INFO - PROCESS | 18754 | 1482411901442 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] 05:07:10 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41938/tests/dromaeo/cssquery-ext.html) 05:07:11 INFO - PROCESS | 18754 | RSS: Main: 232128512 05:07:11 INFO - PROCESS | 18754 | 05:10:13 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-ext.html (next: http://localhost:41938/tests/dromaeo/cssquery-jquery.html) 05:10:14 INFO - PROCESS | 18754 | RSS: Main: 227926016 05:10:14 INFO - PROCESS | 18754 | 05:13:15 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41938/tests/dromaeo/cssquery-mootools.html) 05:13:16 INFO - PROCESS | 18754 | RSS: Main: 208916480 05:13:16 INFO - PROCESS | 18754 | 05:16:18 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41938/tests/dromaeo/cssquery-prototype.html) 05:16:19 INFO - PROCESS | 18754 | RSS: Main: 175812608 05:16:19 INFO - PROCESS | 18754 | 05:19:21 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41938/tests/dromaeo/cssquery-yui.html) 05:19:22 INFO - PROCESS | 18754 | RSS: Main: 178094080 05:19:22 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | Cycle 1(1): loaded http://localhost:41938/tests/dromaeo/cssquery-yui.html (next: http://localhost:41938/tests/dromaeo/cssquery-dojo.html) 05:22:24 INFO - PROCESS | 18754 | RSS: Main: 262725632 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | __start_tp_report 05:22:24 INFO - PROCESS | 18754 | _x_x_mozilla_page_load 05:22:24 INFO - PROCESS | 18754 | _x_x_mozilla_page_load_details 05:22:24 INFO - PROCESS | 18754 | |i|pagename|runs| 05:22:24 INFO - PROCESS | 18754 | |0;dojo.html;3063.5711000459933;3098.969010309896;3108.611423572052;3122.765792565558;3146.2763564380193;5483.561315094793;5607.214989901417;5611.7755289788465;5616.52259557937;5619.100943848985;5497.780088796453;5532.5573954083675;5572.164175373696;5574.358948720898;5585.720713964306;4978.676386034906;5006.624503162259;5035.118854200518;5082.79668813247;5104.668196567211;4927;5079;5103.030424219405;5111.821086261982;5114.181730923053;4875.341828853113;4989.2017277235645;5025.04524140414;5031.094403007475;5037.093323201822;5033.899322013557;5054.721990290533;5063.924041139386;5115.744212789383;5148.970205958786;4982.800687972477;5044.672096313765;5086.694798312076;5092.388913330387;5110.3356563646485;5300.072487314705;5332.840014799563;5455.863603409907;5525.11598144299;5533.861653458655;5840.912386314209;5842.883142337172;5867.559933005042;5867.941320586782;5869.4717475427415;5913.467787899067;5920.2895652521975;5920.822375328747;5928.081147422156;5940.732667029994;5370.194470829368;5423.132298832168;5472.890542189134;5538.6399884007415;5564.360098588633;6073.817785466443;6094.878102437927;6099;6113.8471538211015;6129.570930034854;5838.9124163137585;5854.092615644582;5855.736491857877;5865.061590145556;5865.941340586625;5675.148727690879;5782.797602083907;5788.884222315614;5928.79249226275;5944.851378715567;5849.707514624251;5908.231929849092;5910.556708246899;5912.438318359749;5952.672603006876;5913.349531551526;5915.024021036566;5922.940770592325;5923.644581325134;5944.346121926585;4394.340848872695;4402.0755641315045;4518.367428560005;4518.412606361152;4528.796204170754;3940.763554186758;3968.8214030368704;3978.1049263915966;3985.3822657488136;4026.1142548639255;5537.944620553823;5623.522000629992;5631.971840140773;5637.210790489335;5668.971655141698;5744.540436765049;5921.230240068763;5926.525877929756;5927.73325200367;5930.703464826827;5483.588730845123;5572.693501857356;5605.971970140124;5647.887042259132;5719.313682358061;5037.420696619863;5038.949610503922;5040.546350828443;5041.6470847040355;5048.167052436307;6139.201903752483;6167.907481387872;6179.752809887644;6182.721777520022;6185.567010309235;5719.370869204301;5813.82558523245;5868.3251426086545;5893.6463812171405;5897.439743224559;6089.177960975212;6092.421219984094;6096.268447786294;6102.877942441215;6127.540434467343;5759.625624334405;5809.419058094157;5811.622244554091;5813.157092221689;5814.825555233349;5745.9425405745405;5798.5651076168615;5863.970680146743;5871.7651293949475;5907.852303692442;5851.444112809277;5853.707314634336;5854.795082172103;5875.177475153568;5893.174955506318;5789.739461724148;5835;5848.941510584839;5850.6782126981725;5852.531797456279;5552.389237183826;5600.103983362643;5705.030144875298;5799.333076696234;5803.82588522345;5810.883782324415;5811.593188476766;5854.297484301911;5871.2073870027;5899.4985426239255;5747.453991870766;5756.539476841926;5757.078867381367;5757.280339957505;5758.366579676149 05:22:24 INFO - PROCESS | 18754 | |1;ext.html;590.4685782795483;610.2493932463071;622.8754249150171;633.562841639269;636.7548493829875;34934.47598286028;38416.88707121107;45155.09689806206;45727.314090288586;46038.76980615101;46662;47220.76389618047;47240.52759472413;47261.7636911815;47299.29051064237;8416.873746893802;8589.098144694779;9296.7675808105;9447.669331573396;9457.38526995743;10841.186910981669;11112.388818614972;11124.443777811117;11423;11428.942855285712;11269.436528173599;11372.488238029267;11386;11386;11390.658280251564;11117.72205694856;11159.218854680175;11174.888251117465;11179.552817887276;11183.944080279547;11041;11100.944495277472;11105.333679979227;11114.833277500844;11115.277506962102;45187.774061129814;45502;45519.317210241876;45528.544714552765;45574.772126139156;3896.8636097736726;3938.232044751246;4064.8577299794365;4225.830966761326;4575.95424045762;33090.338193236;33095;33486.66513334884;34201.31597368039;34231.65768342334;4565.52062033485;4689.906201875943;4725.834595789165;4732.834350797705;4826.179549476562;381817;382942;383349;383426;385435.9999999972;60632.696836516414;61133.69433152806;61157;61195.08207376893;61270.69364653148;41841.16317673629;42518.14963700709;42820.78589607032;42857;42912;17882.642347152985;17909.19408626615;17975.820241797675;17981.820181798277;17994.190261438267;13287.734245315232;13501.324933753272;14570.344334504973;14619.26903654813;15047.398104075934;20047.799522004592;20187.798122018885;20332.898335508227;20344.18623255083;20453.693194602092;7401.888971665429;7575.659095340723;7580.583067931207;7657.387409007378;7777.261160189773;1039.0648416425279;1059.2320567588438;1112.1881026850444;1141.2353723005606;1164.6273192578294;33166.33667326688;33666;33833.661663383544;34345;34524.482132768026;1131.3833960491552;1176.5940750441084;1190.4464424042687;1193.2661413230974;1232.2729589542214;17712.380066697602;17932.103394830207;17974.191161397768;18048.458546243633;18109.909450452662;517786;520401;522127;522323;522635;61031.38968610346;61667.69166154141;61756.69121654363;61786.38213617896;61941.69029154826;1315.1714419915393;1357.172125003747;1436.4541474239722;1455.9636009099856;1475.2107622421952;32953.505697415036;34765.82617086898;35009.474857876645;35151.296974059864;35281.294374112884;52024;52275.73862130665;52290.738546307024;52346.476535234164;52430;383813;384388;385133;385495;457227;26830.32924176833;29502.409951801274;30092.849535752182;30485.54271685882;31671.52492712565;49622.25566616432;49953.75023124861;50200;50411;51112.74443627758;41637.79181104196;41692.791536042125;41738.58261417347;41838;43322.35016474818;42104;42650.573494265904;42766;43137.78431107824;44186;42833.143337133704;42961.78519107385;43352.56647433485;43557;43616.34575481307;36863.44704829483;39810;39829.80085099556;39856.60143398529;40091;29679.109626711233;29962.850185748932;29975.80096796191;30003.54994675038;30221.395572088873 05:22:24 INFO - PROCESS | 18754 | |2;jquery.html;426.71623370458644;432.30614863144655;432.4291934646267;434.16613699701753;442.3762494882217;18542;19028.334008309714;20278.898605506987;20306.492337691565;20476.078576464057;19450.319238826643;19877.701834472493;20239.696404553943;20692.68960965583;20749.585008299826;5902.822915312537;6166.907496387557;6177.5057995360485;6180.969095154518;6185.6597887116195;5895.734691938873;6014.6391216527145;6038.69806509675;6047.606905551147;6119.449249567538;5986.01230796918;5998.940010599904;6002.459778619924;6020.397960203988;6020.698965051752;5847.970760146193;5996.070609055603;6074.058520929263;6087.0869369594475;6106.938930610681;5503.174523821419;5513.917291240635;5517.779288828442;5518.978988887069;5519.448055194489;22835.543289134122;22961.885190574107;23745.881270593538;23748.525029499488;23841.403964900845;2616.8953241870304;2706.066407089551;2894.421115776853;2930.6483222013494;3173.4922412413903;11048.226624136314;11114.499847506882;11196.216264861463;14818.62953426173;14905.956583039191;2018.697195420684;2021.6563184258712;2063.8348932085546;2064.7728749837506;2108.515041540439;75201.62399188023;76419.6179019107;77328.22671773267;79008.60495697543;79604;11721.421287316776;11807;12341.62975110748;12351.6912077197;12517.311547864954;17371.65256694859;17410.564735881704;17417.303307867543;17419.041952692725;17429;8776;8881.689140880038;8889.333300002525;8949.955250223837;9222.723318300461;8880.555972201364;8891.599878005505;8920.420172688884;9142.680006199884;9148;5218.7912483500995;5226.660267082628;5263.7104959226845;5263.947360526346;5284.920726189111;1978.5251539630674;2049.4671385439597;2173.793489618484;2203.5923354179527;2209.2157284164214;996.6611352140308;1000.5697550053546;1056.5826498533036;1061.5647584490323;1085.0831047764627;19197.90401047986;20125.025609762168;20582.794172058388;20710.79289207119;21114.049867755988;1024.99487502562;1035.6582327831948;1046.2728403759313;1054.9459315282215;1078.795028944498;10292.433916134685;11383.886161138282;11516.539338426537;11693.649190524127;11711.941440292914;381308;386074;492778;493457;516367;87597.56201218953;92252.53873730589;94530.5273473642;96036.51981740046;104463.4776826111;496.28782696830166;515.3866898390903;516.3803435876888;519.6518332717088;519.859637897773;10857.782844342912;11094.725581255985;11144.2199046066;11167.38579378142;11175.050120739828;41646.58353416427;42199.57800421956;42406.36390454084;42599;43369.13261734811;57576.71211643915;58164.70917645554;58325.70837145957;58855.411445886704;59795.40204598072;8470.416522960557;8763.737087887373;8880.644774209217;8899.510526921082;9277.67528136512;12849.486020559072;13660.86339136596;13751.381187846371;13778.517751878637;13848.584542463743;12085.51657933708;12199.14605977573;12265.754684906431;12281;12339.197952133083;11405.144614154138;11949.64151075469;11954.880451195377;12092.576759813364;12276.508939642314;12099.153059285765;12172.939135304267;12229.388530573613;12288;12446.751064978831;8800.779980500538;8922;8937.329700272376;9031.232345250728;9521.143097121292;9089.727308180765;11334.206605537533;12193.207441516273;12238.75522489563;12339.07456940715 05:22:24 INFO - PROCESS | 18754 | |3;mootools.html;1341.7923868518337;1349.5613925474224;1435.0098432081863;1502.6018105202118;1800.045975632915;7870.763877083682;8381.455205411652;8747.343949203803;8757.562121893912;9022.639094436214;8897.59960801764;9034.05142460042;9060.184583387494;9080.909190908089;9091.04544022878;2495.426052008044;2520.243926821955;2521.319243804172;2523.9747602524017;2528.469021505486;2438.402591365109;2488.0669748844184;2493.613489909058;2494.738052504488;2495.862727549984;2454.006127518358;2461.8645974471488;2495.1142344467726;2505.3360859372283;2509.8494090354548;2428.7328393876746;2440.7559244075537;2447.8286519943613;2483.639872218518;2485.3165379520597;2399.664047033417;2407.325948734357;2431.9513609727883;2432.464857731314;2452.8160387970975;8960.462372257685;8993.055729148411;9025.548722563846;9128.087191280818;9241.075892410772;5459.617826752129;5614.971925140389;5615.0454422748235;5616.326040875121;5630.690312032836;3454.29187016661;3482.1120614243205;3482.181687303482;3510.069831494656;3541.061618671054;1075.231209685079;1099.6975831646282;1156.554726430322;1169.3568537304534;1177.9293242405397;13068.738625227537;13121.47514099435;13139.802902956464;13157.86842131576;13158.86841131586;4765.190284968339;4765.928511072337;4914.8034078637165;4927.507249275044;5034.471380505068;10372.636957706443;10381.377117372982;10385;10403.219758518142;10404.167666586807;5700.600957932905;5706.9714651426475;5708.40061793514;5714.142878568165;5716.742746576331;4596.264597664358;4616.953830461653;5138.58891288696;5177.792888284502;5344.786208551614;3932.134930315326;3988.7806170660892;4073.796310184479;4093.1404405074672;4098.508179018478;1173.747644256489;1212.9818052729215;1242.4657397319058;1245.1221888568537;1252.574124797555;1045.1899777672213;1056.8784589772274;1060.612876300145;1066.1470823341297;1071.892810718922;6124.816255512342;6279.623222606659;6439.356064393617;6562.737490500422;6667.49993750461;500.17970528333564;502.7209898506291;508.70495112834465;511.562613965062;511.72878374461595;4398.582134697199;4453.1761624099;4526.4115664964;4568.291914753218;4623.838165664186;11128.66614001565;11621.941890290494;11636.94181529087;11671.824922626167;11688.824667629993;10822.621208257831;11104.833427498594;11239.943800280946;11512.884871151182;11561.364124973206;8408.705695300636;8531.146885311418;8647.956760216159;8858.29133669305;8860.468371897707;6626.801195964129;6812.182538095461;6895.5173137879865;6904.792856214322;6948.583085014713;10613.469326533488;10680.252382333161;10781.676549703208;10904.34573925567;10948.452577370941;9371;10452.372857628567;10751;10804.783904321712;10816.351018938889;7427.740029098955;7694.1536430993665;7948.125706172432;7973.880391794244;8167.387445941697;8936;9194.218491428304;9199.678011269574;9204.631814727336;9206.355555111079;8178;8259.710910118118;8296;8325.750227493185;8354.707585234732;8116.951130707144;8119.188081191834;8181.836363272821;8197.71308004241;8377;7714.652840622298;7741.690332386866;7743.148253691977;7819.257170568787;7838.255365740246;5444.591655625764;5685.60200785941;5903.26209223847;5951.494122999594;5985.461308482258;5615.606907516598;5677.233573467529;5755.424457554211;5783.392743761844;5842.065269556851 05:22:24 INFO - PROCESS | 18754 | |4;prototype.html;345.09584887594497;352.66320663766106;355.8131980710128;357.83539571796973;363.83263698698596;5505.256790333304;5573.33120025597;5587.608867379285;5590.413006634306;5590.496855283024;5434.619576629638;5442.346918369792;5456.563474922006;5461.208124821898;5521.2270282160525;3739.7008239340926;4095.8361665533344;4196.62230399264;4259.933932042956;4277.422547956033;4253.787670513904;4255.638270746975;4257.212415703089;4278.593533614302;4283.271843786564;4225.894352641178;4245.766482843443;4247.277962746341;4254.595813397737;4262.1688770689725;4209.326507758775;4241.681873859473;4247.5964783345535;4284.335927931175;4308.2029824482415;3792.336341140317;3800.194907418954;3800.8669696560487;3804.2201348723447;3809.257194847006;5265.236145891131;5280.392754833205;5288.338957630296;5298.15229563268;5360.150747991936;4685.484596694361;4880.389951256093;4880.755962201912;4886.315915771795;4897.853064408074;5599.160125981135;5613.129964855413;5660.518855897254;5662.178984047331;5669.6314739541795;4167.74993500391;4168.854090106862;4202.9789851050855;4214.262504061777;4214.810333534998;5653.321601407816;5876.768726516858;5893.734781934823;5918.852028699274;5930.436608522184;4182.91634167315;4184.497860256728;4202.516710578261;4207.894802629959;4207.957920420818;4239.915201695949;4241.554636763095;4262.488501379854;4302.139572085533;4322.286822674287;4681.578657920803;4900.215965445512;4921.3356196912955;4924.975375123173;4925.335079764186;5679.346875109332;5681.886362272814;5685.403032681509;5690.687012214368;5780.913286300709;1453.716525277582;1474.3660226102663;1479.1494890438041;1485.440287697913;1487.4645127754;923.3197813817028;946.7680418297562;957.1768279279814;957.7653474898694;966.2076847447883;4860.4896485869185;4866.688323046525;4876.561109500162;4949.129504887157;4956.851294461172;5206.251498920771;5224.973875130676;5232.424433312332;5242.292290540804;5244.213367994831;4666.2436738425895;4905.632077594195;4987.002599480118;4988.650794444354;5001.224810154432;2721.414895797413;2736.1107640017076;2737.4525094980686;2741.972580274172;2757.3658058646224;5210.76551555181;5254.15933450646;5267.498762852569;5291.259223708684;5359.5712343012465;5229.450907654642;5274.3670759509105;5607.495325420732;6042.728077236535;6048.818535443944;5838;5973.761049557969;5975.312839023568;5978.880422391528;5983.491403230687;5717.971410142923;5897.262842144732;5906.202662640489;5914.822555323347;5917.37867523921;5654.660720356792;5778.624389414675;5805.738741756547;5872.354041055566;5876.471117599436;6067.908981365372;6083.300420451527;6084.47888027993;6094.878102437838;6112.17485639433;4203.327467605169;4208.968802643372;4218.535961044344;4234.682398820162;4263.253930562201;5873.588848780544;5897.174395584536;5907.881842363043;5931.288245410406;5968.73235636522;5667.886642267049;5716.342620598684;5743.827685169452;5767.769289228384;5773.480386765212;5506.893514257179;5724.169995350734;5738.942610574008;5744.4829965303325;5754.9136762947755;5693.601447898607;5712.885742285214;5716;5716.771329146787;5719.256496655575;5736.282964629421;5747.396523365153;5761.049426844524;5769.53843692512;5794.710264486843;5439.265699130567;5590.636608620427;5596.552275817843;5637.802676906289;5644.209810626434 05:22:24 INFO - PROCESS | 18754 | |5;yui.html;828.950262984221;835.6907944060695;835.8871552340436;880.5773228850152;895.978584413768;17196.656066878655;17724.29102835885;17988.460346189604;18085.90957045216;18098.547536311584;16212.189390530486;16392.590185245375;16399.016059036465;16434.671306573862;16912;839.5214727605268;864.0883867519764;866.138192498465;866.6100254885296;866.7486428935601;721.686066561045;738.6269933683483;836.7196989008689;863.6243234193115;866.2290561400358;910.3581974707815;912.4935660708304;915.5239275576696;916.8808054952847;917.0600134861753;905.1220316293218;905.9275257979345;906.3881879731221;906.5150144672568;906.805036917066;664.4817042706696;665.2316574356578;665.6505334699273;665.6804733727789;666.0375757031092;16535.25591348392;16536.503904882873;16563.91718041414;16572;16576.08831514266;16571.585710357216;16668.33326666932;16770.245338959656;17251.3962011329;17776.733348999776;13246.66883327915;13270.601881943461;13323.26722030288;13597.59207223775;13665;4126.298529250034;4144.088300573899;4166.395872598487;4170.52039015514;4280.871573852789;87426.12573874244;87516.56241718815;87693.56153219257;87761.56119219426;87989;13203.075784695171;13350.866491334962;13377.866221337857;13401.597952061258;13420.46318147263;24769.62845557318;24974.250772476855;25061.624075638516;25095.749042509342;25397;8748.562571871382;9127.361084724005;9220.44677319363;9226.07739226072;9414.905850941403;5129.615278854027;5167.0957582422925;5227.111391063529;5308.124159513637;5332.200169974535;3815.3323168445413;3951.2887680217264;3970.3845903884944;3984.8406063757707;3993.560708322082;651.5308977536166;664.9002649602502;670.0686046395514;680.7072958627739;681.8125015620744;657.7040331850686;669.7220653428869;714.8963400307031;723.7828651404558;731.3965978068115;8918.464892106495;9574.617015319449;9646.276529260334;9647.083527064917;9666.854997175047;695.2421860172436;708.7413094220573;711.7259854955879;719.0600574056267;745.3738859358165;4425.601695847325;4569.520200378913;4580.8167673293365;4690.085433340466;5301.3373328333955;118973;120814.3959280198;124707;126453.36773316075;127798.36100819436;99907;101664;101886;102887;104419.47790261;76877.6156119227;93583.53208234052;95706.52146739082;96396;98609;9835.85246221293;10149.695509134737;10154.898451015395;10195.490225488844;10216.642417515352;52502.79977601859;52969;53947.73026134844;55666.44333556775;55756.442435575125;71008.64495677695;71727;78488.60755696186;82833.17166828255;84043.15956840597;13718.45126194941;14197.361118749475;14390.352434140279;14483.348249328596;14492.710145797237;16154.757678635066;16432;16688.916555417632;16780.412685555948;16942.237599308308;14154.150750954976;14343.139411635335;14345.64135896611;15575.92212038978;15749.685006300038;15275.618109546906;15319.923400383374;15569.610759731097;15639.687206255583;16089.517314480585;12406;13617.86382136166;14306.57080287593;14645.92677036608;15478.767818482507;12405.193662411913;12603.684907877376;13110.344482776014;13150.473981041034;13592.388342524808;12219.816702749644;12719.173253738478;12739.426725797546;12905.548305809252;12985.415656295678 05:22:24 INFO - PROCESS | 18754 | __end_tp_report 05:22:24 INFO - PROCESS | 18754 | __start_cc_report 05:22:24 INFO - PROCESS | 18754 | _x_x_mozilla_cycle_collect,852 05:22:24 INFO - PROCESS | 18754 | __end_cc_report 05:22:24 INFO - PROCESS | 18754 | __startTimestamp1482412944881__endTimestamp 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | ------- Summary: start ------- 05:22:24 INFO - PROCESS | 18754 | Number of tests: 6 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#0] dojo.html Cycles:165 Average:5489.45 Median:5747.45 stddev:640.22 (11.1%) stddev-sans-first:613.25 05:22:24 INFO - PROCESS | 18754 | Values: 3063.6 3099.0 3108.6 3122.8 3146.3 5483.6 5607.2 5611.8 5616.5 5619.1 5497.8 5532.6 5572.2 5574.4 5585.7 4978.7 5006.6 5035.1 5082.8 5104.7 4927.0 5079.0 5103.0 5111.8 5114.2 4875.3 4989.2 5025.0 5031.1 5037.1 5033.9 5054.7 5063.9 5115.7 5149.0 4982.8 5044.7 5086.7 5092.4 5110.3 5300.1 5332.8 5455.9 5525.1 5533.9 5840.9 5842.9 5867.6 5867.9 5869.5 5913.5 5920.3 5920.8 5928.1 5940.7 5370.2 5423.1 5472.9 5538.6 5564.4 6073.8 6094.9 6099.0 6113.8 6129.6 5838.9 5854.1 5855.7 5865.1 5865.9 5675.1 5782.8 5788.9 5928.8 5944.9 5849.7 5908.2 5910.6 5912.4 5952.7 5913.3 5915.0 5922.9 5923.6 5944.3 4394.3 4402.1 4518.4 4518.4 4528.8 3940.8 3968.8 3978.1 3985.4 4026.1 5537.9 5623.5 5632.0 5637.2 5669.0 5744.5 5921.2 5926.5 5927.7 5930.7 5483.6 5572.7 5606.0 5647.9 5719.3 5037.4 5038.9 5040.5 5041.6 5048.2 6139.2 6167.9 6179.8 6182.7 6185.6 5719.4 5813.8 5868.3 5893.6 5897.4 6089.2 6092.4 6096.3 6102.9 6127.5 5759.6 5809.4 5811.6 5813.2 5814.8 5745.9 5798.6 5864.0 5871.8 5907.9 5851.4 5853.7 5854.8 5875.2 5893.2 5789.7 5835.0 5848.9 5850.7 5852.5 5552.4 5600.1 5705.0 5799.3 5803.8 5810.9 5811.6 5854.3 5871.2 5899.5 5747.5 5756.5 5757.1 5757.3 5758.4 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#1] ext.html Cycles:180 Average:60571.03 Median:385465.50 stddev:115978.62 (30.1%) stddev-sans-first:116216.53 05:22:24 INFO - PROCESS | 18754 | Values: 590.5 610.2 622.9 633.6 636.8 34934.5 38416.9 45155.1 45727.3 46038.8 46662.0 47220.8 47240.5 47261.8 47299.3 8416.9 8589.1 9296.8 9447.7 9457.4 10841.2 11112.4 11124.4 11423.0 11428.9 11269.4 11372.5 11386.0 11386.0 11390.7 11117.7 11159.2 11174.9 11179.6 11183.9 11041.0 11100.9 11105.3 11114.8 11115.3 45187.8 45502.0 45519.3 45528.5 45574.8 3896.9 3938.2 4064.9 4225.8 4576.0 33090.3 33095.0 33486.7 34201.3 34231.7 4565.5 4689.9 4725.8 4732.8 4826.2 381817.0 382942.0 383349.0 383426.0 385436.0 60632.7 61133.7 61157.0 61195.1 61270.7 41841.2 42518.1 42820.8 42857.0 42912.0 17882.6 17909.2 17975.8 17981.8 17994.2 13287.7 13501.3 14570.3 14619.3 15047.4 20047.8 20187.8 20332.9 20344.2 20453.7 7401.9 7575.7 7580.6 7657.4 7777.3 1039.1 1059.2 1112.2 1141.2 1164.6 33166.3 33666.0 33833.7 34345.0 34524.5 1131.4 1176.6 1190.4 1193.3 1232.3 17712.4 17932.1 17974.2 18048.5 18109.9 517786.0 520401.0 522127.0 522323.0 522635.0 61031.4 61667.7 61756.7 61786.4 61941.7 1315.2 1357.2 1436.5 1456.0 1475.2 32953.5 34765.8 35009.5 35151.3 35281.3 52024.0 52275.7 52290.7 52346.5 52430.0 383813.0 384388.0 385133.0 385495.0 457227.0 26830.3 29502.4 30092.8 30485.5 31671.5 49622.3 49953.8 50200.0 50411.0 51112.7 41637.8 41692.8 41738.6 41838.0 43322.4 42104.0 42650.6 42766.0 43137.8 44186.0 42833.1 42961.8 43352.6 43557.0 43616.3 36863.4 39810.0 39829.8 39856.6 40091.0 29679.1 29962.9 29975.8 30003.5 30221.4 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#2] jquery.html Cycles:180 Average:28279.15 Median:2800.24 stddev:75656.38 (2701.8%) stddev-sans-first:75839.71 05:22:24 INFO - PROCESS | 18754 | Values: 426.7 432.3 432.4 434.2 442.4 18542.0 19028.3 20278.9 20306.5 20476.1 19450.3 19877.7 20239.7 20692.7 20749.6 5902.8 6166.9 6177.5 6181.0 6185.7 5895.7 6014.6 6038.7 6047.6 6119.4 5986.0 5998.9 6002.5 6020.4 6020.7 5848.0 5996.1 6074.1 6087.1 6106.9 5503.2 5513.9 5517.8 5519.0 5519.4 22835.5 22961.9 23745.9 23748.5 23841.4 2616.9 2706.1 2894.4 2930.6 3173.5 11048.2 11114.5 11196.2 14818.6 14906.0 2018.7 2021.7 2063.8 2064.8 2108.5 75201.6 76419.6 77328.2 79008.6 79604.0 11721.4 11807.0 12341.6 12351.7 12517.3 17371.7 17410.6 17417.3 17419.0 17429.0 8776.0 8881.7 8889.3 8950.0 9222.7 8880.6 8891.6 8920.4 9142.7 9148.0 5218.8 5226.7 5263.7 5263.9 5284.9 1978.5 2049.5 2173.8 2203.6 2209.2 996.7 1000.6 1056.6 1061.6 1085.1 19197.9 20125.0 20582.8 20710.8 21114.0 1025.0 1035.7 1046.3 1054.9 1078.8 10292.4 11383.9 11516.5 11693.6 11711.9 381308.0 386074.0 492778.0 493457.0 516367.0 87597.6 92252.5 94530.5 96036.5 104463.5 496.3 515.4 516.4 519.7 519.9 10857.8 11094.7 11144.2 11167.4 11175.1 41646.6 42199.6 42406.4 42599.0 43369.1 57576.7 58164.7 58325.7 58855.4 59795.4 8470.4 8763.7 8880.6 8899.5 9277.7 12849.5 13660.9 13751.4 13778.5 13848.6 12085.5 12199.1 12265.8 12281.0 12339.2 11405.1 11949.6 11954.9 12092.6 12276.5 12099.2 12172.9 12229.4 12288.0 12446.8 8800.8 8922.0 8937.3 9031.2 9521.1 9089.7 11334.2 12193.2 12238.8 12339.1 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#3] mootools.html Cycles:180 Average:6107.37 Median:4694.51 stddev:3465.01 (73.8%) stddev-sans-first:3456.22 05:22:24 INFO - PROCESS | 18754 | Values: 1341.8 1349.6 1435.0 1502.6 1800.0 7870.8 8381.5 8747.3 8757.6 9022.6 8897.6 9034.1 9060.2 9080.9 9091.0 2495.4 2520.2 2521.3 2524.0 2528.5 2438.4 2488.1 2493.6 2494.7 2495.9 2454.0 2461.9 2495.1 2505.3 2509.8 2428.7 2440.8 2447.8 2483.6 2485.3 2399.7 2407.3 2432.0 2432.5 2452.8 8960.5 8993.1 9025.5 9128.1 9241.1 5459.6 5615.0 5615.0 5616.3 5630.7 3454.3 3482.1 3482.2 3510.1 3541.1 1075.2 1099.7 1156.6 1169.4 1177.9 13068.7 13121.5 13139.8 13157.9 13158.9 4765.2 4765.9 4914.8 4927.5 5034.5 10372.6 10381.4 10385.0 10403.2 10404.2 5700.6 5707.0 5708.4 5714.1 5716.7 4596.3 4617.0 5138.6 5177.8 5344.8 3932.1 3988.8 4073.8 4093.1 4098.5 1173.7 1213.0 1242.5 1245.1 1252.6 1045.2 1056.9 1060.6 1066.1 1071.9 6124.8 6279.6 6439.4 6562.7 6667.5 500.2 502.7 508.7 511.6 511.7 4398.6 4453.2 4526.4 4568.3 4623.8 11128.7 11621.9 11636.9 11671.8 11688.8 10822.6 11104.8 11239.9 11512.9 11561.4 8408.7 8531.1 8648.0 8858.3 8860.5 6626.8 6812.2 6895.5 6904.8 6948.6 10613.5 10680.3 10781.7 10904.3 10948.5 9371.0 10452.4 10751.0 10804.8 10816.4 7427.7 7694.2 7948.1 7973.9 8167.4 8936.0 9194.2 9199.7 9204.6 9206.4 8178.0 8259.7 8296.0 8325.8 8354.7 8117.0 8119.2 8181.8 8197.7 8377.0 7714.7 7741.7 7743.1 7819.3 7838.3 5444.6 5685.6 5903.3 5951.5 5985.5 5615.6 5677.2 5755.4 5783.4 5842.1 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#4] prototype.html Cycles:180 Average:4733.56 Median:5277.38 stddev:1389.64 (26.3%) stddev-sans-first:1353.94 05:22:24 INFO - PROCESS | 18754 | Values: 345.1 352.7 355.8 357.8 363.8 5505.3 5573.3 5587.6 5590.4 5590.5 5434.6 5442.3 5456.6 5461.2 5521.2 3739.7 4095.8 4196.6 4259.9 4277.4 4253.8 4255.6 4257.2 4278.6 4283.3 4225.9 4245.8 4247.3 4254.6 4262.2 4209.3 4241.7 4247.6 4284.3 4308.2 3792.3 3800.2 3800.9 3804.2 3809.3 5265.2 5280.4 5288.3 5298.2 5360.2 4685.5 4880.4 4880.8 4886.3 4897.9 5599.2 5613.1 5660.5 5662.2 5669.6 4167.7 4168.9 4203.0 4214.3 4214.8 5653.3 5876.8 5893.7 5918.9 5930.4 4182.9 4184.5 4202.5 4207.9 4208.0 4239.9 4241.6 4262.5 4302.1 4322.3 4681.6 4900.2 4921.3 4925.0 4925.3 5679.3 5681.9 5685.4 5690.7 5780.9 1453.7 1474.4 1479.1 1485.4 1487.5 923.3 946.8 957.2 957.8 966.2 4860.5 4866.7 4876.6 4949.1 4956.9 5206.3 5225.0 5232.4 5242.3 5244.2 4666.2 4905.6 4987.0 4988.7 5001.2 2721.4 2736.1 2737.5 2742.0 2757.4 5210.8 5254.2 5267.5 5291.3 5359.6 5229.5 5274.4 5607.5 6042.7 6048.8 5838.0 5973.8 5975.3 5978.9 5983.5 5718.0 5897.3 5906.2 5914.8 5917.4 5654.7 5778.6 5805.7 5872.4 5876.5 6067.9 6083.3 6084.5 6094.9 6112.2 4203.3 4209.0 4218.5 4234.7 4263.3 5873.6 5897.2 5907.9 5931.3 5968.7 5667.9 5716.3 5743.8 5767.8 5773.5 5506.9 5724.2 5738.9 5744.5 5754.9 5693.6 5712.9 5716.0 5716.8 5719.3 5736.3 5747.4 5761.0 5769.5 5794.7 5439.3 5590.6 5596.6 5637.8 5644.2 05:22:24 INFO - PROCESS | 18754 | 05:22:24 INFO - PROCESS | 18754 | [#5] yui.html Cycles:180 Average:22562.26 Median:4168.46 stddev:31999.70 (767.7%) stddev-sans-first:32047.86 05:22:24 INFO - PROCESS | 18754 | Values: 829.0 835.7 835.9 880.6 896.0 17196.7 17724.3 17988.5 18085.9 18098.5 16212.2 16392.6 16399.0 16434.7 16912.0 839.5 864.1 866.1 866.6 866.7 721.7 738.6 836.7 863.6 866.2 910.4 912.5 915.5 916.9 917.1 905.1 905.9 906.4 906.5 906.8 664.5 665.2 665.7 665.7 666.0 16535.3 16536.5 16563.9 16572.0 16576.1 16571.6 16668.3 16770.2 17251.4 17776.7 13246.7 13270.6 13323.3 13597.6 13665.0 4126.3 4144.1 4166.4 4170.5 4280.9 87426.1 87516.6 87693.6 87761.6 87989.0 13203.1 13350.9 13377.9 13401.6 13420.5 24769.6 24974.3 25061.6 25095.7 25397.0 8748.6 9127.4 9220.4 9226.1 9414.9 5129.6 5167.1 5227.1 5308.1 5332.2 3815.3 3951.3 3970.4 3984.8 3993.6 651.5 664.9 670.1 680.7 681.8 657.7 669.7 714.9 723.8 731.4 8918.5 9574.6 9646.3 9647.1 9666.9 695.2 708.7 711.7 719.1 745.4 4425.6 4569.5 4580.8 4690.1 5301.3 118973.0 120814.4 124707.0 126453.4 127798.4 99907.0 101664.0 101886.0 102887.0 104419.5 76877.6 93583.5 95706.5 96396.0 98609.0 9835.9 10149.7 10154.9 10195.5 10216.6 52502.8 52969.0 53947.7 55666.4 55756.4 71008.6 71727.0 78488.6 82833.2 84043.2 13718.5 14197.4 14390.4 14483.3 14492.7 16154.8 16432.0 16688.9 16780.4 16942.2 14154.2 14343.1 14345.6 15575.9 15749.7 15275.6 15319.9 15569.6 15639.7 16089.5 12406.0 13617.9 14306.6 14645.9 15478.8 12405.2 12603.7 13110.3 13150.5 13592.4 12219.8 12719.2 12739.4 12905.5 12985.4 05:22:24 INFO - PROCESS | 18754 | -------- Summary: end -------- 05:22:24 INFO - PROCESS | 18754 | 05:22:25 INFO - TEST-INFO | 18754: exit 0 05:22:25 INFO - TEST-OK | dromaeo_css | took 1111985ms 05:22:25 INFO - TEST-START | kraken 05:22:25 INFO - operating with platform_type : linux_ 05:22:25 INFO - Initialising browser for kraken test... 05:22:25 INFO - TEST-INFO | started process 19059 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTvAwQU/profile http://localhost:41938/getInfo.html) 05:22:29 INFO - PROCESS | 19059 | __metrics Screen width/height:1600/1200 05:22:29 INFO - PROCESS | 19059 | colorDepth:24 05:22:29 INFO - PROCESS | 19059 | Browser inner width/height: 1024/697 05:22:29 INFO - PROCESS | 19059 | __metrics 05:22:29 INFO - PROCESS | 19059 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:22:29 INFO - PROCESS | 19059 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:22:30 INFO - PROCESS | 19059 | console.error: PushService: 05:22:30 INFO - PROCESS | 19059 | stateChangeProcessEnqueue: Error transitioning state 05:22:30 INFO - PROCESS | 19059 | UnknownError 05:22:30 INFO - TEST-INFO | 19059: exit 0 05:22:30 INFO - Browser initialized. 05:22:30 INFO - Running cycle 1/1 for kraken test... 05:22:30 INFO - TEST-INFO | started process 19150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTvAwQU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:42 INFO - PROCESS | 19150 | RSS: Main: 143839232 05:22:42 INFO - PROCESS | 19150 | 05:22:45 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41938/tests/kraken/driver.html?testName=audio-beat-detection) 05:22:45 INFO - PROCESS | 19150 | RSS: Main: 186793984 05:22:45 INFO - PROCESS | 19150 | 05:22:48 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41938/tests/kraken/driver.html?testName=audio-dft) 05:22:49 INFO - PROCESS | 19150 | RSS: Main: 174514176 05:22:49 INFO - PROCESS | 19150 | 05:22:51 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41938/tests/kraken/driver.html?testName=audio-fft) 05:22:52 INFO - PROCESS | 19150 | RSS: Main: 266186752 05:22:52 INFO - PROCESS | 19150 | 05:22:54 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41938/tests/kraken/driver.html?testName=audio-oscillator) 05:22:55 INFO - PROCESS | 19150 | RSS: Main: 171991040 05:22:55 INFO - PROCESS | 19150 | 05:22:57 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41938/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:57 INFO - PROCESS | 19150 | RSS: Main: 173817856 05:22:57 INFO - PROCESS | 19150 | 05:23:00 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41938/tests/kraken/driver.html?testName=imaging-darkroom) 05:23:01 INFO - PROCESS | 19150 | RSS: Main: 209805312 05:23:01 INFO - PROCESS | 19150 | 05:23:04 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41938/tests/kraken/driver.html?testName=imaging-desaturate) 05:23:05 INFO - PROCESS | 19150 | RSS: Main: 208322560 05:23:05 INFO - PROCESS | 19150 | 05:23:07 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41938/tests/kraken/driver.html?testName=json-parse-financial) 05:23:08 INFO - PROCESS | 19150 | RSS: Main: 208138240 05:23:08 INFO - PROCESS | 19150 | 05:23:10 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41938/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:23:10 INFO - PROCESS | 19150 | RSS: Main: 169975808 05:23:10 INFO - PROCESS | 19150 | 05:23:12 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:23:12 INFO - PROCESS | 19150 | RSS: Main: 177782784 05:23:12 INFO - PROCESS | 19150 | 05:23:14 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:23:15 INFO - PROCESS | 19150 | RSS: Main: 187785216 05:23:15 INFO - PROCESS | 19150 | 05:23:18 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:23:18 INFO - PROCESS | 19150 | RSS: Main: 195817472 05:23:18 INFO - PROCESS | 19150 | 05:23:21 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:23:21 INFO - PROCESS | 19150 | RSS: Main: 192516096 05:23:21 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | Cycle 1(1): loaded http://localhost:41938/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41938/tests/kraken/driver.html?testName=ai-astar) 05:23:23 INFO - PROCESS | 19150 | RSS: Main: 243425280 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | __start_tp_report 05:23:23 INFO - PROCESS | 19150 | _x_x_mozilla_page_load 05:23:23 INFO - PROCESS | 19150 | _x_x_mozilla_page_load_details 05:23:23 INFO - PROCESS | 19150 | |i|pagename|runs| 05:23:23 INFO - PROCESS | 19150 | |0;ai-astar;111;111;110;110;110;109;110;110;110;111 05:23:23 INFO - PROCESS | 19150 | |1;audio-beat-detection;147;119;120;155;118;117;119;116;156;117 05:23:23 INFO - PROCESS | 19150 | |2;audio-dft;160;161;161;163;160;160;160;160;160;161 05:23:23 INFO - PROCESS | 19150 | |3;audio-fft;75;75;74;101;77;76;75;75;103;77 05:23:23 INFO - PROCESS | 19150 | |4;audio-oscillator;88;81;120;81;82;82;106;81;80;113 05:23:23 INFO - PROCESS | 19150 | |5;imaging-gaussian-blur;116;116;117;117;118;117;117;117;117;117 05:23:23 INFO - PROCESS | 19150 | |6;imaging-darkroom;173;146;146;146;145;145;145;146;153;146 05:23:23 INFO - PROCESS | 19150 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;94;95 05:23:23 INFO - PROCESS | 19150 | |8;json-parse-financial;54;51;50;50;51;50;50;51;49;50 05:23:23 INFO - PROCESS | 19150 | |9;json-stringify-tinderbox;59;59;60;60;60;59;60;59;59;59 05:23:23 INFO - PROCESS | 19150 | |10;stanford-crypto-aes;73;80;77;72;79;75;74;75;81;75 05:23:23 INFO - PROCESS | 19150 | |11;stanford-crypto-ccm;130;120;120;135;118;126;119;126;169;121 05:23:23 INFO - PROCESS | 19150 | |12;stanford-crypto-pbkdf2;158;153;150;154;155;158;152;151;151;150 05:23:23 INFO - PROCESS | 19150 | |13;stanford-crypto-sha256-iterative;64;63;64;61;62;60;61;60;60;60 05:23:23 INFO - PROCESS | 19150 | __end_tp_report 05:23:23 INFO - PROCESS | 19150 | __start_cc_report 05:23:23 INFO - PROCESS | 19150 | _x_x_mozilla_cycle_collect,1604 05:23:23 INFO - PROCESS | 19150 | __end_cc_report 05:23:23 INFO - PROCESS | 19150 | __startTimestamp1482413003831__endTimestamp 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | ------- Summary: start ------- 05:23:23 INFO - PROCESS | 19150 | Number of tests: 14 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 05:23:23 INFO - PROCESS | 19150 | Values: 111.0 111.0 110.0 110.0 110.0 109.0 110.0 110.0 110.0 111.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:119.50 stddev:16.95 (14.2%) stddev-sans-first:16.58 05:23:23 INFO - PROCESS | 19150 | Values: 147.0 119.0 120.0 155.0 118.0 117.0 119.0 116.0 156.0 117.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.50 stddev:0.97 (0.6%) stddev-sans-first:1.00 05:23:23 INFO - PROCESS | 19150 | Values: 160.0 161.0 161.0 163.0 160.0 160.0 160.0 160.0 160.0 161.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:11.22 (15.0%) stddev-sans-first:11.71 05:23:23 INFO - PROCESS | 19150 | Values: 75.0 75.0 74.0 101.0 77.0 76.0 75.0 75.0 103.0 77.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:81.00 stddev:15.42 (19.0%) stddev-sans-first:16.31 05:23:23 INFO - PROCESS | 19150 | Values: 88.0 81.0 120.0 81.0 82.0 82.0 106.0 81.0 80.0 113.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 05:23:23 INFO - PROCESS | 19150 | Values: 116.0 116.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:146.00 stddev:8.72 (6.0%) stddev-sans-first:2.51 05:23:23 INFO - PROCESS | 19150 | Values: 173.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 153.0 146.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:23:23 INFO - PROCESS | 19150 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.50 stddev:1.35 (2.7%) stddev-sans-first:0.67 05:23:23 INFO - PROCESS | 19150 | Values: 54.0 51.0 50.0 50.0 51.0 50.0 50.0 51.0 49.0 50.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 05:23:23 INFO - PROCESS | 19150 | Values: 59.0 59.0 60.0 60.0 60.0 59.0 60.0 59.0 59.0 59.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.00 stddev:3.03 (4.0%) stddev-sans-first:3.00 05:23:23 INFO - PROCESS | 19150 | Values: 73.0 80.0 77.0 72.0 79.0 75.0 74.0 75.0 81.0 75.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:126.00 stddev:15.27 (12.1%) stddev-sans-first:16.18 05:23:23 INFO - PROCESS | 19150 | Values: 130.0 120.0 120.0 135.0 118.0 126.0 119.0 126.0 169.0 121.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.50 stddev:3.01 (2.0%) stddev-sans-first:2.65 05:23:23 INFO - PROCESS | 19150 | Values: 158.0 153.0 150.0 154.0 155.0 158.0 152.0 151.0 151.0 150.0 05:23:23 INFO - PROCESS | 19150 | 05:23:23 INFO - PROCESS | 19150 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:1.65 (2.7%) stddev-sans-first:1.48 05:23:23 INFO - PROCESS | 19150 | Values: 64.0 63.0 64.0 61.0 62.0 60.0 61.0 60.0 60.0 60.0 05:23:23 INFO - PROCESS | 19150 | -------- Summary: end -------- 05:23:23 INFO - PROCESS | 19150 | 05:23:24 INFO - TEST-INFO | 19150: exit 0 05:23:24 INFO - TEST-OK | kraken | took 59205ms 05:23:24 INFO - SUITE-END | took 1171s 05:23:24 INFO - Completed test suite (00:19:31) 05:23:24 INFO - javascript benchmark 05:23:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3063.5711000459933, 3098.969010309896, 3108.611423572052, 3122.765792565558, 3146.2763564380193, 5483.561315094793, 5607.214989901417, 5611.7755289788465, 5616.52259557937, 5619.100943848985, 5497.780088796453, 5532.5573954083675, 5572.164175373696, 5574.358948720898, 5585.720713964306, 4978.676386034906, 5006.624503162259, 5035.118854200518, 5082.79668813247, 5104.668196567211, 4927.0, 5079.0, 5103.030424219405, 5111.821086261982, 5114.181730923053, 4875.341828853113, 4989.2017277235645, 5025.04524140414, 5031.094403007475, 5037.093323201822, 5033.899322013557, 5054.721990290533, 5063.924041139386, 5115.744212789383, 5148.970205958786, 4982.800687972477, 5044.672096313765, 5086.694798312076, 5092.388913330387, 5110.3356563646485, 5300.072487314705, 5332.840014799563, 5455.863603409907, 5525.11598144299, 5533.861653458655, 5840.912386314209, 5842.883142337172, 5867.559933005042, 5867.941320586782, 5869.4717475427415, 5913.467787899067, 5920.2895652521975, 5920.822375328747, 5928.081147422156, 5940.732667029994, 5370.194470829368, 5423.132298832168, 5472.890542189134, 5538.6399884007415, 5564.360098588633, 6073.817785466443, 6094.878102437927, 6099.0, 6113.8471538211015, 6129.570930034854, 5838.9124163137585, 5854.092615644582, 5855.736491857877, 5865.061590145556, 5865.941340586625, 5675.148727690879, 5782.797602083907, 5788.884222315614, 5928.79249226275, 5944.851378715567, 5849.707514624251, 5908.231929849092, 5910.556708246899, 5912.438318359749, 5952.672603006876, 5913.349531551526, 5915.024021036566, 5922.940770592325, 5923.644581325134, 5944.346121926585, 4394.340848872695, 4402.0755641315045, 4518.367428560005, 4518.412606361152, 4528.796204170754, 3940.763554186758, 3968.8214030368704, 3978.1049263915966, 3985.3822657488136, 4026.1142548639255, 5537.944620553823, 5623.522000629992, 5631.971840140773, 5637.210790489335, 5668.971655141698, 5744.540436765049, 5921.230240068763, 5926.525877929756, 5927.73325200367, 5930.703464826827, 5483.588730845123, 5572.693501857356, 5605.971970140124, 5647.887042259132, 5719.313682358061, 5037.420696619863, 5038.949610503922, 5040.546350828443, 5041.6470847040355, 5048.167052436307, 6139.201903752483, 6167.907481387872, 6179.752809887644, 6182.721777520022, 6185.567010309235, 5719.370869204301, 5813.82558523245, 5868.3251426086545, 5893.6463812171405, 5897.439743224559, 6089.177960975212, 6092.421219984094, 6096.268447786294, 6102.877942441215, 6127.540434467343, 5759.625624334405, 5809.419058094157, 5811.622244554091, 5813.157092221689, 5814.825555233349, 5745.9425405745405, 5798.5651076168615, 5863.970680146743, 5871.7651293949475, 5907.852303692442, 5851.444112809277, 5853.707314634336, 5854.795082172103, 5875.177475153568, 5893.174955506318, 5789.739461724148, 5835.0, 5848.941510584839, 5850.6782126981725, 5852.531797456279, 5552.389237183826, 5600.103983362643, 5705.030144875298, 5799.333076696234, 5803.82588522345, 5810.883782324415, 5811.593188476766, 5854.297484301911, 5871.2073870027, 5899.4985426239255, 5747.453991870766, 5756.539476841926, 5757.078867381367, 5757.280339957505, 5758.366579676149], "value": 5444.383697643291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.4685782795483, 610.2493932463071, 622.8754249150171, 633.562841639269, 636.7548493829875, 34934.47598286028, 38416.88707121107, 45155.09689806206, 45727.314090288586, 46038.76980615101, 46662.0, 47220.76389618047, 47240.52759472413, 47261.7636911815, 47299.29051064237, 8416.873746893802, 8589.098144694779, 9296.7675808105, 9447.669331573396, 9457.38526995743, 10841.186910981669, 11112.388818614972, 11124.443777811117, 11423.0, 11428.942855285712, 11269.436528173599, 11372.488238029267, 11386.0, 11386.0, 11390.658280251564, 11117.72205694856, 11159.218854680175, 11174.888251117465, 11179.552817887276, 11183.944080279547, 11041.0, 11100.944495277472, 11105.333679979227, 11114.833277500844, 11115.277506962102, 45187.774061129814, 45502.0, 45519.317210241876, 45528.544714552765, 45574.772126139156, 3896.8636097736726, 3938.232044751246, 4064.8577299794365, 4225.830966761326, 4575.95424045762, 33090.338193236, 33095.0, 33486.66513334884, 34201.31597368039, 34231.65768342334, 4565.52062033485, 4689.906201875943, 4725.834595789165, 4732.834350797705, 4826.179549476562, 381817.0, 382942.0, 383349.0, 383426.0, 385435.9999999972, 60632.696836516414, 61133.69433152806, 61157.0, 61195.08207376893, 61270.69364653148, 41841.16317673629, 42518.14963700709, 42820.78589607032, 42857.0, 42912.0, 17882.642347152985, 17909.19408626615, 17975.820241797675, 17981.820181798277, 17994.190261438267, 13287.734245315232, 13501.324933753272, 14570.344334504973, 14619.26903654813, 15047.398104075934, 20047.799522004592, 20187.798122018885, 20332.898335508227, 20344.18623255083, 20453.693194602092, 7401.888971665429, 7575.659095340723, 7580.583067931207, 7657.387409007378, 7777.261160189773, 1039.0648416425279, 1059.2320567588438, 1112.1881026850444, 1141.2353723005606, 1164.6273192578294, 33166.33667326688, 33666.0, 33833.661663383544, 34345.0, 34524.482132768026, 1131.3833960491552, 1176.5940750441084, 1190.4464424042687, 1193.2661413230974, 1232.2729589542214, 17712.380066697602, 17932.103394830207, 17974.191161397768, 18048.458546243633, 18109.909450452662, 517786.0, 520401.0, 522127.0, 522323.0, 522635.0, 61031.38968610346, 61667.69166154141, 61756.69121654363, 61786.38213617896, 61941.69029154826, 1315.1714419915393, 1357.172125003747, 1436.4541474239722, 1455.9636009099856, 1475.2107622421952, 32953.505697415036, 34765.82617086898, 35009.474857876645, 35151.296974059864, 35281.294374112884, 52024.0, 52275.73862130665, 52290.738546307024, 52346.476535234164, 52430.0, 383813.0, 384388.0, 385133.0, 385495.0, 457227.0, 26830.32924176833, 29502.409951801274, 30092.849535752182, 30485.54271685882, 31671.52492712565, 49622.25566616432, 49953.75023124861, 50200.0, 50411.0, 51112.74443627758, 41637.79181104196, 41692.791536042125, 41738.58261417347, 41838.0, 43322.35016474818, 42104.0, 42650.573494265904, 42766.0, 43137.78431107824, 44186.0, 42833.143337133704, 42961.78519107385, 43352.56647433485, 43557.0, 43616.34575481307, 36863.44704829483, 39810.0, 39829.80085099556, 39856.60143398529, 40091.0, 29679.109626711233, 29962.850185748932, 29975.80096796191, 30003.54994675038, 30221.395572088873], "value": 21357.9394159164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.71623370458644, 432.30614863144655, 432.4291934646267, 434.16613699701753, 442.3762494882217, 18542.0, 19028.334008309714, 20278.898605506987, 20306.492337691565, 20476.078576464057, 19450.319238826643, 19877.701834472493, 20239.696404553943, 20692.68960965583, 20749.585008299826, 5902.822915312537, 6166.907496387557, 6177.5057995360485, 6180.969095154518, 6185.6597887116195, 5895.734691938873, 6014.6391216527145, 6038.69806509675, 6047.606905551147, 6119.449249567538, 5986.01230796918, 5998.940010599904, 6002.459778619924, 6020.397960203988, 6020.698965051752, 5847.970760146193, 5996.070609055603, 6074.058520929263, 6087.0869369594475, 6106.938930610681, 5503.174523821419, 5513.917291240635, 5517.779288828442, 5518.978988887069, 5519.448055194489, 22835.543289134122, 22961.885190574107, 23745.881270593538, 23748.525029499488, 23841.403964900845, 2616.8953241870304, 2706.066407089551, 2894.421115776853, 2930.6483222013494, 3173.4922412413903, 11048.226624136314, 11114.499847506882, 11196.216264861463, 14818.62953426173, 14905.956583039191, 2018.697195420684, 2021.6563184258712, 2063.8348932085546, 2064.7728749837506, 2108.515041540439, 75201.62399188023, 76419.6179019107, 77328.22671773267, 79008.60495697543, 79604.0, 11721.421287316776, 11807.0, 12341.62975110748, 12351.6912077197, 12517.311547864954, 17371.65256694859, 17410.564735881704, 17417.303307867543, 17419.041952692725, 17429.0, 8776.0, 8881.689140880038, 8889.333300002525, 8949.955250223837, 9222.723318300461, 8880.555972201364, 8891.599878005505, 8920.420172688884, 9142.680006199884, 9148.0, 5218.7912483500995, 5226.660267082628, 5263.7104959226845, 5263.947360526346, 5284.920726189111, 1978.5251539630674, 2049.4671385439597, 2173.793489618484, 2203.5923354179527, 2209.2157284164214, 996.6611352140308, 1000.5697550053546, 1056.5826498533036, 1061.5647584490323, 1085.0831047764627, 19197.90401047986, 20125.025609762168, 20582.794172058388, 20710.79289207119, 21114.049867755988, 1024.99487502562, 1035.6582327831948, 1046.2728403759313, 1054.9459315282215, 1078.795028944498, 10292.433916134685, 11383.886161138282, 11516.539338426537, 11693.649190524127, 11711.941440292914, 381308.0, 386074.0, 492778.0, 493457.0, 516367.0, 87597.56201218953, 92252.53873730589, 94530.5273473642, 96036.51981740046, 104463.4776826111, 496.28782696830166, 515.3866898390903, 516.3803435876888, 519.6518332717088, 519.859637897773, 10857.782844342912, 11094.725581255985, 11144.2199046066, 11167.38579378142, 11175.050120739828, 41646.58353416427, 42199.57800421956, 42406.36390454084, 42599.0, 43369.13261734811, 57576.71211643915, 58164.70917645554, 58325.70837145957, 58855.411445886704, 59795.40204598072, 8470.416522960557, 8763.737087887373, 8880.644774209217, 8899.510526921082, 9277.67528136512, 12849.486020559072, 13660.86339136596, 13751.381187846371, 13778.517751878637, 13848.584542463743, 12085.51657933708, 12199.14605977573, 12265.754684906431, 12281.0, 12339.197952133083, 11405.144614154138, 11949.64151075469, 11954.880451195377, 12092.576759813364, 12276.508939642314, 12099.153059285765, 12172.939135304267, 12229.388530573613, 12288.0, 12446.751064978831, 8800.779980500538, 8922.0, 8937.329700272376, 9031.232345250728, 9521.143097121292, 9089.727308180765, 11334.206605537533, 12193.207441516273, 12238.75522489563, 12339.07456940715], "value": 9408.912057341066, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7923868518337, 1349.5613925474224, 1435.0098432081863, 1502.6018105202118, 1800.045975632915, 7870.763877083682, 8381.455205411652, 8747.343949203803, 8757.562121893912, 9022.639094436214, 8897.59960801764, 9034.05142460042, 9060.184583387494, 9080.909190908089, 9091.04544022878, 2495.426052008044, 2520.243926821955, 2521.319243804172, 2523.9747602524017, 2528.469021505486, 2438.402591365109, 2488.0669748844184, 2493.613489909058, 2494.738052504488, 2495.862727549984, 2454.006127518358, 2461.8645974471488, 2495.1142344467726, 2505.3360859372283, 2509.8494090354548, 2428.7328393876746, 2440.7559244075537, 2447.8286519943613, 2483.639872218518, 2485.3165379520597, 2399.664047033417, 2407.325948734357, 2431.9513609727883, 2432.464857731314, 2452.8160387970975, 8960.462372257685, 8993.055729148411, 9025.548722563846, 9128.087191280818, 9241.075892410772, 5459.617826752129, 5614.971925140389, 5615.0454422748235, 5616.326040875121, 5630.690312032836, 3454.29187016661, 3482.1120614243205, 3482.181687303482, 3510.069831494656, 3541.061618671054, 1075.231209685079, 1099.6975831646282, 1156.554726430322, 1169.3568537304534, 1177.9293242405397, 13068.738625227537, 13121.47514099435, 13139.802902956464, 13157.86842131576, 13158.86841131586, 4765.190284968339, 4765.928511072337, 4914.8034078637165, 4927.507249275044, 5034.471380505068, 10372.636957706443, 10381.377117372982, 10385.0, 10403.219758518142, 10404.167666586807, 5700.600957932905, 5706.9714651426475, 5708.40061793514, 5714.142878568165, 5716.742746576331, 4596.264597664358, 4616.953830461653, 5138.58891288696, 5177.792888284502, 5344.786208551614, 3932.134930315326, 3988.7806170660892, 4073.796310184479, 4093.1404405074672, 4098.508179018478, 1173.747644256489, 1212.9818052729215, 1242.4657397319058, 1245.1221888568537, 1252.574124797555, 1045.1899777672213, 1056.8784589772274, 1060.612876300145, 1066.1470823341297, 1071.892810718922, 6124.816255512342, 6279.623222606659, 6439.356064393617, 6562.737490500422, 6667.49993750461, 500.17970528333564, 502.7209898506291, 508.70495112834465, 511.562613965062, 511.72878374461595, 4398.582134697199, 4453.1761624099, 4526.4115664964, 4568.291914753218, 4623.838165664186, 11128.66614001565, 11621.941890290494, 11636.94181529087, 11671.824922626167, 11688.824667629993, 10822.621208257831, 11104.833427498594, 11239.943800280946, 11512.884871151182, 11561.364124973206, 8408.705695300636, 8531.146885311418, 8647.956760216159, 8858.29133669305, 8860.468371897707, 6626.801195964129, 6812.182538095461, 6895.5173137879865, 6904.792856214322, 6948.583085014713, 10613.469326533488, 10680.252382333161, 10781.676549703208, 10904.34573925567, 10948.452577370941, 9371.0, 10452.372857628567, 10751.0, 10804.783904321712, 10816.351018938889, 7427.740029098955, 7694.1536430993665, 7948.125706172432, 7973.880391794244, 8167.387445941697, 8936.0, 9194.218491428304, 9199.678011269574, 9204.631814727336, 9206.355555111079, 8178.0, 8259.710910118118, 8296.0, 8325.750227493185, 8354.707585234732, 8116.951130707144, 8119.188081191834, 8181.836363272821, 8197.71308004241, 8377.0, 7714.652840622298, 7741.690332386866, 7743.148253691977, 7819.257170568787, 7838.255365740246, 5444.591655625764, 5685.60200785941, 5903.26209223847, 5951.494122999594, 5985.461308482258, 5615.606907516598, 5677.233573467529, 5755.424457554211, 5783.392743761844, 5842.065269556851], "value": 4802.222732950535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.09584887594497, 352.66320663766106, 355.8131980710128, 357.83539571796973, 363.83263698698596, 5505.256790333304, 5573.33120025597, 5587.608867379285, 5590.413006634306, 5590.496855283024, 5434.619576629638, 5442.346918369792, 5456.563474922006, 5461.208124821898, 5521.2270282160525, 3739.7008239340926, 4095.8361665533344, 4196.62230399264, 4259.933932042956, 4277.422547956033, 4253.787670513904, 4255.638270746975, 4257.212415703089, 4278.593533614302, 4283.271843786564, 4225.894352641178, 4245.766482843443, 4247.277962746341, 4254.595813397737, 4262.1688770689725, 4209.326507758775, 4241.681873859473, 4247.5964783345535, 4284.335927931175, 4308.2029824482415, 3792.336341140317, 3800.194907418954, 3800.8669696560487, 3804.2201348723447, 3809.257194847006, 5265.236145891131, 5280.392754833205, 5288.338957630296, 5298.15229563268, 5360.150747991936, 4685.484596694361, 4880.389951256093, 4880.755962201912, 4886.315915771795, 4897.853064408074, 5599.160125981135, 5613.129964855413, 5660.518855897254, 5662.178984047331, 5669.6314739541795, 4167.74993500391, 4168.854090106862, 4202.9789851050855, 4214.262504061777, 4214.810333534998, 5653.321601407816, 5876.768726516858, 5893.734781934823, 5918.852028699274, 5930.436608522184, 4182.91634167315, 4184.497860256728, 4202.516710578261, 4207.894802629959, 4207.957920420818, 4239.915201695949, 4241.554636763095, 4262.488501379854, 4302.139572085533, 4322.286822674287, 4681.578657920803, 4900.215965445512, 4921.3356196912955, 4924.975375123173, 4925.335079764186, 5679.346875109332, 5681.886362272814, 5685.403032681509, 5690.687012214368, 5780.913286300709, 1453.716525277582, 1474.3660226102663, 1479.1494890438041, 1485.440287697913, 1487.4645127754, 923.3197813817028, 946.7680418297562, 957.1768279279814, 957.7653474898694, 966.2076847447883, 4860.4896485869185, 4866.688323046525, 4876.561109500162, 4949.129504887157, 4956.851294461172, 5206.251498920771, 5224.973875130676, 5232.424433312332, 5242.292290540804, 5244.213367994831, 4666.2436738425895, 4905.632077594195, 4987.002599480118, 4988.650794444354, 5001.224810154432, 2721.414895797413, 2736.1107640017076, 2737.4525094980686, 2741.972580274172, 2757.3658058646224, 5210.76551555181, 5254.15933450646, 5267.498762852569, 5291.259223708684, 5359.5712343012465, 5229.450907654642, 5274.3670759509105, 5607.495325420732, 6042.728077236535, 6048.818535443944, 5838.0, 5973.761049557969, 5975.312839023568, 5978.880422391528, 5983.491403230687, 5717.971410142923, 5897.262842144732, 5906.202662640489, 5914.822555323347, 5917.37867523921, 5654.660720356792, 5778.624389414675, 5805.738741756547, 5872.354041055566, 5876.471117599436, 6067.908981365372, 6083.300420451527, 6084.47888027993, 6094.878102437838, 6112.17485639433, 4203.327467605169, 4208.968802643372, 4218.535961044344, 4234.682398820162, 4263.253930562201, 5873.588848780544, 5897.174395584536, 5907.881842363043, 5931.288245410406, 5968.73235636522, 5667.886642267049, 5716.342620598684, 5743.827685169452, 5767.769289228384, 5773.480386765212, 5506.893514257179, 5724.169995350734, 5738.942610574008, 5744.4829965303325, 5754.9136762947755, 5693.601447898607, 5712.885742285214, 5716.0, 5716.771329146787, 5719.256496655575, 5736.282964629421, 5747.396523365153, 5761.049426844524, 5769.53843692512, 5794.710264486843, 5439.265699130567, 5590.636608620427, 5596.552275817843, 5637.802676906289, 5644.209810626434], "value": 4295.838526497207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.950262984221, 835.6907944060695, 835.8871552340436, 880.5773228850152, 895.978584413768, 17196.656066878655, 17724.29102835885, 17988.460346189604, 18085.90957045216, 18098.547536311584, 16212.189390530486, 16392.590185245375, 16399.016059036465, 16434.671306573862, 16912.0, 839.5214727605268, 864.0883867519764, 866.138192498465, 866.6100254885296, 866.7486428935601, 721.686066561045, 738.6269933683483, 836.7196989008689, 863.6243234193115, 866.2290561400358, 910.3581974707815, 912.4935660708304, 915.5239275576696, 916.8808054952847, 917.0600134861753, 905.1220316293218, 905.9275257979345, 906.3881879731221, 906.5150144672568, 906.805036917066, 664.4817042706696, 665.2316574356578, 665.6505334699273, 665.6804733727789, 666.0375757031092, 16535.25591348392, 16536.503904882873, 16563.91718041414, 16572.0, 16576.08831514266, 16571.585710357216, 16668.33326666932, 16770.245338959656, 17251.3962011329, 17776.733348999776, 13246.66883327915, 13270.601881943461, 13323.26722030288, 13597.59207223775, 13665.0, 4126.298529250034, 4144.088300573899, 4166.395872598487, 4170.52039015514, 4280.871573852789, 87426.12573874244, 87516.56241718815, 87693.56153219257, 87761.56119219426, 87989.0, 13203.075784695171, 13350.866491334962, 13377.866221337857, 13401.597952061258, 13420.46318147263, 24769.62845557318, 24974.250772476855, 25061.624075638516, 25095.749042509342, 25397.0, 8748.562571871382, 9127.361084724005, 9220.44677319363, 9226.07739226072, 9414.905850941403, 5129.615278854027, 5167.0957582422925, 5227.111391063529, 5308.124159513637, 5332.200169974535, 3815.3323168445413, 3951.2887680217264, 3970.3845903884944, 3984.8406063757707, 3993.560708322082, 651.5308977536166, 664.9002649602502, 670.0686046395514, 680.7072958627739, 681.8125015620744, 657.7040331850686, 669.7220653428869, 714.8963400307031, 723.7828651404558, 731.3965978068115, 8918.464892106495, 9574.617015319449, 9646.276529260334, 9647.083527064917, 9666.854997175047, 695.2421860172436, 708.7413094220573, 711.7259854955879, 719.0600574056267, 745.3738859358165, 4425.601695847325, 4569.520200378913, 4580.8167673293365, 4690.085433340466, 5301.3373328333955, 118973.0, 120814.3959280198, 124707.0, 126453.36773316075, 127798.36100819436, 99907.0, 101664.0, 101886.0, 102887.0, 104419.47790261, 76877.6156119227, 93583.53208234052, 95706.52146739082, 96396.0, 98609.0, 9835.85246221293, 10149.695509134737, 10154.898451015395, 10195.490225488844, 10216.642417515352, 52502.79977601859, 52969.0, 53947.73026134844, 55666.44333556775, 55756.442435575125, 71008.64495677695, 71727.0, 78488.60755696186, 82833.17166828255, 84043.15956840597, 13718.45126194941, 14197.361118749475, 14390.352434140279, 14483.348249328596, 14492.710145797237, 16154.757678635066, 16432.0, 16688.916555417632, 16780.412685555948, 16942.237599308308, 14154.150750954976, 14343.139411635335, 14345.64135896611, 15575.92212038978, 15749.685006300038, 15275.618109546906, 15319.923400383374, 15569.610759731097, 15639.687206255583, 16089.517314480585, 12406.0, 13617.86382136166, 14306.57080287593, 14645.92677036608, 15478.767818482507, 12405.193662411913, 12603.684907877376, 13110.344482776014, 13150.473981041034, 13592.388342524808, 12219.816702749644, 12719.173253738478, 12739.426725797546, 12905.548305809252, 12985.415656295678], "value": 8203.14224992145, "unit": "score"}], "name": "dromaeo_css", "value": 7549.657646891611, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 110.0, 111.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 120.0, 155.0, 118.0, 117.0, 119.0, 116.0, 156.0, 117.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 163.0, 160.0, 160.0, 160.0, 160.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 101.0, 77.0, 76.0, 75.0, 75.0, 103.0, 77.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 120.0, 81.0, 82.0, 82.0, 106.0, 81.0, 80.0, 113.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 153.0, 146.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 50.0, 50.0, 51.0, 50.0, 50.0, 51.0, 49.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 80.0, 77.0, 72.0, 79.0, 75.0, 74.0, 75.0, 81.0, 75.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 120.0, 120.0, 135.0, 118.0, 126.0, 119.0, 126.0, 169.0, 121.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 153.0, 150.0, 154.0, 155.0, 158.0, 152.0, 151.0, 151.0, 150.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 61.0, 62.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1461.4, "alertThreshold": 2.0}]} 05:23:25 INFO - Return code: 0 05:23:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:23:25 INFO - # TBPL SUCCESS # 05:23:25 INFO - Running post-action listener: _resource_record_post_action 05:23:25 INFO - [mozharness: 2016-12-22 13:23:25.164739Z] Finished run-tests step (success) 05:23:25 INFO - Running post-run listener: _resource_record_post_run 05:23:25 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 1744896; Write bytes: 325255168; Read time: 232; Write time: 184612 05:23:25 INFO - TinderboxPrint: CPU usage
12.9% 05:23:25 INFO - TinderboxPrint: I/O read bytes / time
1,744,896 / 232 05:23:25 INFO - TinderboxPrint: I/O write bytes / time
325,255,168 / 184,612 05:23:25 INFO - TinderboxPrint: CPU idle
8,236.0 (87.1%) 05:23:25 INFO - TinderboxPrint: CPU user
1,182.2 (12.5%) 05:23:25 INFO - TinderboxPrint: Swap in / out
0 / 0 05:23:25 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 4096; Write bytes: 116817920; Read time: 172; Write time: 64548 05:23:25 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 208437248; Read time: 36; Write time: 120064 05:23:25 INFO - Running post-run listener: _upload_blobber_files 05:23:25 INFO - Blob upload gear active. 05:23:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:23:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:23:25 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:23:25 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:23:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:23:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:26 INFO - (blobuploader) - INFO - Done attempting. 05:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:23:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:27 INFO - (blobuploader) - INFO - Done attempting. 05:23:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:23:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:23:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:30 INFO - (blobuploader) - INFO - Done attempting. 05:23:30 INFO - (blobuploader) - INFO - Iteration through files over. 05:23:30 INFO - Return code: 0 05:23:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:23:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:23:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f58669aafab2d69c54d39b0c22ab8416283100471e097cdc3dd3305132a71a7d6e43197c61403ccbfb0d923cf781f04ae4cae512194788f7898ec645eeff1ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a53c2f54ba552490046adf74cd4ccbb3da35b1939acf100295ce66facb690268eb1623866c5d78b5fdb40356b91dd476b5b2c83b241d06206470269da08cca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2884ef1dc9dcd7064c49898373dbb50094d57b87f5f744fed0f494ba5230454f289826c828f57a10b6502fbbca2a8878d03eab82719dbf969bac147a1cbb497c"} 05:23:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:23:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:23:30 INFO - Contents: 05:23:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f58669aafab2d69c54d39b0c22ab8416283100471e097cdc3dd3305132a71a7d6e43197c61403ccbfb0d923cf781f04ae4cae512194788f7898ec645eeff1ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a53c2f54ba552490046adf74cd4ccbb3da35b1939acf100295ce66facb690268eb1623866c5d78b5fdb40356b91dd476b5b2c83b241d06206470269da08cca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2884ef1dc9dcd7064c49898373dbb50094d57b87f5f744fed0f494ba5230454f289826c828f57a10b6502fbbca2a8878d03eab82719dbf969bac147a1cbb497c"} 05:23:30 INFO - Running post-run listener: copy_logs_to_upload_dir 05:23:30 INFO - Copying logs to upload dir... 05:23:30 INFO - mkdir: /builds/slave/test/build/upload/logs 05:23:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.326136 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-12-22 05:23:30.808498) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 05:23:30.813400) ========= 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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f58669aafab2d69c54d39b0c22ab8416283100471e097cdc3dd3305132a71a7d6e43197c61403ccbfb0d923cf781f04ae4cae512194788f7898ec645eeff1ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a53c2f54ba552490046adf74cd4ccbb3da35b1939acf100295ce66facb690268eb1623866c5d78b5fdb40356b91dd476b5b2c83b241d06206470269da08cca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2884ef1dc9dcd7064c49898373dbb50094d57b87f5f744fed0f494ba5230454f289826c828f57a10b6502fbbca2a8878d03eab82719dbf969bac147a1cbb497c"} build_url:https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/A8GgcZWfR0ierEIUYB65AA/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6f58669aafab2d69c54d39b0c22ab8416283100471e097cdc3dd3305132a71a7d6e43197c61403ccbfb0d923cf781f04ae4cae512194788f7898ec645eeff1ff", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a53c2f54ba552490046adf74cd4ccbb3da35b1939acf100295ce66facb690268eb1623866c5d78b5fdb40356b91dd476b5b2c83b241d06206470269da08cca2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2884ef1dc9dcd7064c49898373dbb50094d57b87f5f744fed0f494ba5230454f289826c828f57a10b6502fbbca2a8878d03eab82719dbf969bac147a1cbb497c"}' ========= master_lag: 0.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 05:23:31.082682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:23:31.083048) ========= 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=0333a256a5e4f052382dd180000001b1-1482408361.764773-1804584821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004399 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 05:23:31.165754) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 05:23:31.169098) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 05:23:31.169383) ========= ========= Total master_lag: 0.42 =========