builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1476721176.93 results: success (0) buildid: 20161017061949 builduid: 43f14f1066e348e2880f8eae0f51595a revision: fcc05dd319e0479bf61387c33ca3c7051bfaeb0b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:36.925569) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:36.926048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:36.926317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:36.942077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:36.942400) ========= 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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-17 09:19:37-- 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% 214M=0s 2016-10-17 09:19:37 (214 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115438 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:37.071472) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:37.071799) ========= 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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018045 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:37.121482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 09:19:37.121835) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcc05dd319e0479bf61387c33ca3c7051bfaeb0b --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-aurora --rev fcc05dd319e0479bf61387c33ca3c7051bfaeb0b --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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-17 09:19:37,233 truncating revision to first 12 chars 2016-10-17 09:19:37,233 Setting DEBUG logging. 2016-10-17 09:19:37,233 attempt 1/10 2016-10-17 09:19:37,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcc05dd319e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 09:19:38,050 unpacking tar archive at: mozilla-aurora-fcc05dd319e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.240142 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 09:19:38.376653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:38.377201) ========= script_repo_revision: fcc05dd319e0479bf61387c33ca3c7051bfaeb0b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:38.377874) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:38.378378) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 09:19:38.389771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-17 09:19:38.390229) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:19:38 INFO - MultiFileLogger online at 20161017 09:19:38 in /builds/slave/test 09:19:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:19:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:19:38 INFO - {'append_to_log': False, 09:19:38 INFO - 'base_work_dir': '/builds/slave/test', 09:19:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:19:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:19:38 INFO - 'branch': 'Mozilla-Aurora', 09:19:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:19:38 INFO - 'config_files': ('talos/linux_config.py',), 09:19:38 INFO - 'default_actions': ('clobber', 09:19:38 INFO - 'read-buildbot-config', 09:19:38 INFO - 'download-and-extract', 09:19:38 INFO - 'populate-webroot', 09:19:38 INFO - 'create-virtualenv', 09:19:38 INFO - 'install', 09:19:38 INFO - 'run-tests'), 09:19:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:19:38 INFO - 'download_minidump_stackwalk': True, 09:19:38 INFO - 'download_symbols': 'ondemand', 09:19:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:19:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:19:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:19:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:19:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:19:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:19:38 INFO - 'installer_path': 'installer.exe', 09:19:38 INFO - 'log_level': 'info', 09:19:38 INFO - 'log_name': 'talos', 09:19:38 INFO - 'log_to_console': True, 09:19:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:19:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:19:38 INFO - 'opt_config_files': (), 09:19:38 INFO - 'pip_index': False, 09:19:38 INFO - 'sps_profile': False, 09:19:38 INFO - 'sps_profile_interval': 0, 09:19:38 INFO - 'suite': 'dromaeojs', 09:19:38 INFO - 'system_bits': '32', 09:19:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:19:38 INFO - 'title': 'talos-linux64-ix-016', 09:19:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:19:38 INFO - 'use_talos_json': True, 09:19:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:19:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:19:38 INFO - 'work_dir': 'build'} 09:19:38 INFO - [mozharness: 2016-10-17 16:19:38.601643Z] Running clobber step. 09:19:38 INFO - Running pre-action listener: _resource_record_pre_action 09:19:38 INFO - Running main action method: clobber 09:19:38 INFO - rmtree: /builds/slave/test/build 09:19:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:19:39 INFO - Running post-action listener: _resource_record_post_action 09:19:39 INFO - [mozharness: 2016-10-17 16:19:39.581691Z] Finished clobber step (success) 09:19:39 INFO - [mozharness: 2016-10-17 16:19:39.581790Z] Running read-buildbot-config step. 09:19:39 INFO - Running pre-action listener: _resource_record_pre_action 09:19:39 INFO - Running main action method: read_buildbot_config 09:19:39 INFO - Using buildbot properties: 09:19:39 INFO - { 09:19:39 INFO - "project": "", 09:19:39 INFO - "product": "firefox", 09:19:39 INFO - "script_repo_revision": "production", 09:19:39 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:19:39 INFO - "repository": "", 09:19:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:19:39 INFO - "buildid": "20161017061949", 09:19:39 INFO - "pgo_build": "True", 09:19:39 INFO - "basedir": "/builds/slave/test", 09:19:39 INFO - "buildnumber": 822, 09:19:39 INFO - "slavename": "talos-linux64-ix-016", 09:19:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:19:39 INFO - "platform": "ubuntu64_hw", 09:19:39 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:19:39 INFO - "branch": "mozilla-aurora", 09:19:39 INFO - "repo_path": "releases/mozilla-aurora", 09:19:39 INFO - "revision": "fcc05dd319e0479bf61387c33ca3c7051bfaeb0b", 09:19:39 INFO - "stage_platform": "linux64-pgo", 09:19:39 INFO - "builduid": "43f14f1066e348e2880f8eae0f51595a", 09:19:39 INFO - "slavebuilddir": "test" 09:19:39 INFO - } 09:19:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:19:39 INFO - Running post-action listener: _resource_record_post_action 09:19:39 INFO - [mozharness: 2016-10-17 16:19:39.584236Z] Finished read-buildbot-config step (success) 09:19:39 INFO - [mozharness: 2016-10-17 16:19:39.584325Z] Running download-and-extract step. 09:19:39 INFO - Running pre-action listener: _resource_record_pre_action 09:19:39 INFO - Running main action method: download_and_extract 09:19:39 INFO - mkdir: /builds/slave/test/build/tests 09:19: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')]} 09:19:39 INFO - trying https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:19:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:19:40 INFO - Downloaded 1361 bytes. 09:19:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:19:40 INFO - Using the following test package requirements: 09:19:40 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:19:40 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:19:40 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:19:40 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'jsshell-linux-x86_64.zip'], 09:19:40 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:19:40 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:19:40 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:19:40 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:19:40 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:19:40 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:19:40 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:19:40 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:19:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:19:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:19:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:19:40 INFO - Expected file size: 21907513 09:19:45 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:19:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:19:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:19:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:19:45 INFO - Expected file size: 21907513 09:19:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:19:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:19:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:19:51 INFO - Expected file size: 14414081 09:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:51 INFO - trying https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:19:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:19:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:19:52 INFO - Downloaded 63988435 bytes. 09:19:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:19:52 INFO - mkdir: /builds/slave/test/properties 09:19:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:19:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:19:52 INFO - Contents: 09:19:52 INFO - build_url:https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:19:53 INFO - Running post-action listener: _resource_record_post_action 09:19:53 INFO - Running post-action listener: set_extra_try_arguments 09:19:53 INFO - [mozharness: 2016-10-17 16:19:53.574365Z] Finished download-and-extract step (success) 09:19:53 INFO - [mozharness: 2016-10-17 16:19:53.574667Z] Running populate-webroot step. 09:19:53 INFO - Running pre-action listener: _resource_record_pre_action 09:19:53 INFO - Running main action method: populate_webroot 09:19:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:19:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:19:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:19:53 INFO - '--tppagecycles', 09:19:53 INFO - '7'], 09:19:53 INFO - 'tests': ['tsvgm']}}, 09:19:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:19:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:19:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:19:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:19:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:19:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['damp', 'tps']}, 09:19:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'tests': ['damp', 'tps']}, 09:19:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['dromaeo_dom']}, 09:19:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:19:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['basic_compositor_video']}, 09:19:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:19:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['a11yr', 09:19:53 INFO - 'ts_paint', 09:19:53 INFO - 'tpaint', 09:19:53 INFO - 'sessionrestore', 09:19:53 INFO - 'sessionrestore_no_auto_restore', 09:19:53 INFO - 'tabpaint']}, 09:19:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['tsvgx', 09:19:53 INFO - 'tsvgr_opacity', 09:19:53 INFO - 'tart', 09:19:53 INFO - 'tscrollx', 09:19:53 INFO - 'cart']}, 09:19:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:19:53 INFO - 'tsvgr_opacity', 09:19:53 INFO - 'tart', 09:19:53 INFO - 'tscrollx', 09:19:53 INFO - 'cart']}, 09:19:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'talos_options': ['--disable-e10s'], 09:19:53 INFO - 'tests': ['tp5o']}, 09:19:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'tests': ['tp5o']}, 09:19:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'talos_options': ['--disable-e10s', 09:19:53 INFO - '--xperf_path', 09:19:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:53 INFO - 'tests': ['tp5n']}, 09:19:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:19:53 INFO - 'talos_options': ['--xperf_path', 09:19:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:19:53 INFO - 'tests': ['tp5n']}}, 09:19:53 INFO - 'talos.zip': {'path': '', 09:19:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:19:53 INFO - Running post-action listener: _resource_record_post_action 09:19:53 INFO - [mozharness: 2016-10-17 16:19:53.598710Z] Finished populate-webroot step (success) 09:19:53 INFO - [mozharness: 2016-10-17 16:19:53.598801Z] Running create-virtualenv step. 09:19:53 INFO - Running pre-action listener: _resource_record_pre_action 09:19:53 INFO - Running main action method: create_virtualenv 09:19:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:19:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:19:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:19:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:19:53 INFO - Using real prefix '/usr' 09:19:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:19:54 INFO - Installing distribute.............................................................................................................................................................................................done. 09:19:56 INFO - Installing pip.................done. 09:19:56 INFO - Return code: 0 09:19:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:19:56 INFO - Using env: {'DISPLAY': ':0', 09:19:56 INFO - 'HOME': '/home/cltbld', 09:19:56 INFO - 'LANG': 'en_US.UTF-8', 09:19:56 INFO - 'LANGUAGE': 'en_US:en', 09:19:56 INFO - 'LOGNAME': 'cltbld', 09:19:56 INFO - 'MAIL': '/var/mail/cltbld', 09:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:56 INFO - 'MOZ_NO_REMOTE': '1', 09:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:56 INFO - 'NO_EM_RESTART': '1', 09:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:56 INFO - 'PWD': '/builds/slave/test', 09:19:56 INFO - 'SHELL': '/bin/bash', 09:19:56 INFO - 'SHLVL': '1', 09:19:56 INFO - 'TERM': 'linux', 09:19:56 INFO - 'TMOUT': '86400', 09:19:56 INFO - 'USER': 'cltbld', 09:19:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:56 INFO - '_': '/tools/buildbot/bin/python'} 09:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:19:56 INFO - Cleaning up... 09:19:56 INFO - Return code: 0 09:19:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:19:56 INFO - Using env: {'DISPLAY': ':0', 09:19:56 INFO - 'HOME': '/home/cltbld', 09:19:56 INFO - 'LANG': 'en_US.UTF-8', 09:19:56 INFO - 'LANGUAGE': 'en_US:en', 09:19:56 INFO - 'LOGNAME': 'cltbld', 09:19:56 INFO - 'MAIL': '/var/mail/cltbld', 09:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:56 INFO - 'MOZ_NO_REMOTE': '1', 09:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:56 INFO - 'NO_EM_RESTART': '1', 09:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:56 INFO - 'PWD': '/builds/slave/test', 09:19:56 INFO - 'SHELL': '/bin/bash', 09:19:56 INFO - 'SHLVL': '1', 09:19:56 INFO - 'TERM': 'linux', 09:19:56 INFO - 'TMOUT': '86400', 09:19:56 INFO - 'USER': 'cltbld', 09:19:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:56 INFO - '_': '/tools/buildbot/bin/python'} 09:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:56 INFO - Downloading/unpacking psutil>=3.1.1 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:19:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:58 INFO - Installing collected packages: psutil 09:19:58 INFO - Running setup.py install for psutil 09:19:58 INFO - building 'psutil._psutil_linux' extension 09:19:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:19:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:19:58 INFO - building 'psutil._psutil_posix' extension 09:19:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:19:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:19:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:59 INFO - Successfully installed psutil 09:19:59 INFO - Cleaning up... 09:19:59 INFO - Return code: 0 09:19:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:19:59 INFO - Using env: {'DISPLAY': ':0', 09:19:59 INFO - 'HOME': '/home/cltbld', 09:19:59 INFO - 'LANG': 'en_US.UTF-8', 09:19:59 INFO - 'LANGUAGE': 'en_US:en', 09:19:59 INFO - 'LOGNAME': 'cltbld', 09:19:59 INFO - 'MAIL': '/var/mail/cltbld', 09:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:59 INFO - 'MOZ_NO_REMOTE': '1', 09:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:19:59 INFO - 'NO_EM_RESTART': '1', 09:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:59 INFO - 'PWD': '/builds/slave/test', 09:19:59 INFO - 'SHELL': '/bin/bash', 09:19:59 INFO - 'SHLVL': '1', 09:19:59 INFO - 'TERM': 'linux', 09:19:59 INFO - 'TMOUT': '86400', 09:19:59 INFO - 'USER': 'cltbld', 09:19:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:59 INFO - '_': '/tools/buildbot/bin/python'} 09:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:20:01 INFO - Installing collected packages: mozsystemmonitor 09:20:01 INFO - Running setup.py install for mozsystemmonitor 09:20:01 INFO - Successfully installed mozsystemmonitor 09:20:01 INFO - Cleaning up... 09:20:01 INFO - Return code: 0 09:20:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:01 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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:20:01 INFO - Using env: {'DISPLAY': ':0', 09:20:01 INFO - 'HOME': '/home/cltbld', 09:20:01 INFO - 'LANG': 'en_US.UTF-8', 09:20:01 INFO - 'LANGUAGE': 'en_US:en', 09:20:01 INFO - 'LOGNAME': 'cltbld', 09:20:01 INFO - 'MAIL': '/var/mail/cltbld', 09:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:01 INFO - 'MOZ_NO_REMOTE': '1', 09:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:01 INFO - 'NO_EM_RESTART': '1', 09:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:01 INFO - 'PWD': '/builds/slave/test', 09:20:01 INFO - 'SHELL': '/bin/bash', 09:20:01 INFO - 'SHLVL': '1', 09:20:01 INFO - 'TERM': 'linux', 09:20:01 INFO - 'TMOUT': '86400', 09:20:01 INFO - 'USER': 'cltbld', 09:20:01 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:01 INFO - '_': '/tools/buildbot/bin/python'} 09:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:01 INFO - Downloading/unpacking jsonschema==2.5.1 09:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:20:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:20:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:20:03 INFO - warning: no files found matching '*.txt' 09:20:03 INFO - no previously-included directories found matching 'build' 09:20:03 INFO - no previously-included directories found matching 'dist' 09:20:03 INFO - no previously-included directories found matching '.git*' 09:20:03 INFO - Installing collected packages: jsonschema, functools32 09:20:03 INFO - Running setup.py install for functools32 09:20:04 INFO - warning: no files found matching '*.txt' 09:20:04 INFO - no previously-included directories found matching 'build' 09:20:04 INFO - no previously-included directories found matching 'dist' 09:20:04 INFO - no previously-included directories found matching '.git*' 09:20:04 INFO - Successfully installed jsonschema functools32 09:20:04 INFO - Cleaning up... 09:20:04 INFO - Return code: 0 09:20:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:20:04 INFO - Using env: {'DISPLAY': ':0', 09:20:04 INFO - 'HOME': '/home/cltbld', 09:20:04 INFO - 'LANG': 'en_US.UTF-8', 09:20:04 INFO - 'LANGUAGE': 'en_US:en', 09:20:04 INFO - 'LOGNAME': 'cltbld', 09:20:04 INFO - 'MAIL': '/var/mail/cltbld', 09:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:04 INFO - 'MOZ_NO_REMOTE': '1', 09:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:04 INFO - 'NO_EM_RESTART': '1', 09:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:04 INFO - 'PWD': '/builds/slave/test', 09:20:04 INFO - 'SHELL': '/bin/bash', 09:20:04 INFO - 'SHLVL': '1', 09:20:04 INFO - 'TERM': 'linux', 09:20:04 INFO - 'TMOUT': '86400', 09:20:04 INFO - 'USER': 'cltbld', 09:20:04 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:04 INFO - '_': '/tools/buildbot/bin/python'} 09:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:20:04 INFO - Cleaning up... 09:20:04 INFO - Return code: 0 09:20:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:20:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:20:04 INFO - Using env: {'DISPLAY': ':0', 09:20:04 INFO - 'HOME': '/home/cltbld', 09:20:04 INFO - 'LANG': 'en_US.UTF-8', 09:20:04 INFO - 'LANGUAGE': 'en_US:en', 09:20:04 INFO - 'LOGNAME': 'cltbld', 09:20:04 INFO - 'MAIL': '/var/mail/cltbld', 09:20:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:04 INFO - 'MOZ_NO_REMOTE': '1', 09:20:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:04 INFO - 'NO_EM_RESTART': '1', 09:20:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:04 INFO - 'PWD': '/builds/slave/test', 09:20:04 INFO - 'SHELL': '/bin/bash', 09:20:04 INFO - 'SHLVL': '1', 09:20:04 INFO - 'TERM': 'linux', 09:20:04 INFO - 'TMOUT': '86400', 09:20:04 INFO - 'USER': 'cltbld', 09:20:04 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:04 INFO - '_': '/tools/buildbot/bin/python'} 09:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:04 INFO - Downloading/unpacking blobuploader==1.2.4 09:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - Downloading blobuploader-1.2.4.tar.gz 09:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:20:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:20:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:07 INFO - Downloading docopt-0.6.1.tar.gz 09:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:20:07 INFO - Installing collected packages: blobuploader, requests, docopt 09:20:07 INFO - Running setup.py install for blobuploader 09:20:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:20:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:20:07 INFO - Running setup.py install for requests 09:20:07 INFO - Running setup.py install for docopt 09:20:07 INFO - Successfully installed blobuploader requests docopt 09:20:07 INFO - Cleaning up... 09:20:07 INFO - Return code: 0 09:20:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:07 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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:07 INFO - Using env: {'DISPLAY': ':0', 09:20:07 INFO - 'HOME': '/home/cltbld', 09:20:07 INFO - 'LANG': 'en_US.UTF-8', 09:20:07 INFO - 'LANGUAGE': 'en_US:en', 09:20:07 INFO - 'LOGNAME': 'cltbld', 09:20:07 INFO - 'MAIL': '/var/mail/cltbld', 09:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:07 INFO - 'MOZ_NO_REMOTE': '1', 09:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:07 INFO - 'NO_EM_RESTART': '1', 09:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:07 INFO - 'PWD': '/builds/slave/test', 09:20:07 INFO - 'SHELL': '/bin/bash', 09:20:07 INFO - 'SHLVL': '1', 09:20:07 INFO - 'TERM': 'linux', 09:20:07 INFO - 'TMOUT': '86400', 09:20:07 INFO - 'USER': 'cltbld', 09:20:07 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:07 INFO - '_': '/tools/buildbot/bin/python'} 09:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:08 INFO - Running setup.py (path:/tmp/pip-8aMikX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:08 INFO - Running setup.py (path:/tmp/pip-umZohp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:08 INFO - Running setup.py (path:/tmp/pip-kPqyOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:08 INFO - Running setup.py (path:/tmp/pip-uQjma7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:08 INFO - Running setup.py (path:/tmp/pip-qoNKOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:08 INFO - Running setup.py (path:/tmp/pip-QqrVA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:08 INFO - Running setup.py (path:/tmp/pip-h0Spy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:08 INFO - Running setup.py (path:/tmp/pip-hCXJmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:08 INFO - Running setup.py (path:/tmp/pip-ZPug17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:08 INFO - Running setup.py (path:/tmp/pip-9Ldew6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:08 INFO - Running setup.py (path:/tmp/pip-wM1J3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:08 INFO - Running setup.py (path:/tmp/pip-s_Mu7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:09 INFO - Running setup.py (path:/tmp/pip-jLjdD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:09 INFO - Running setup.py (path:/tmp/pip-1GOdS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:09 INFO - Running setup.py (path:/tmp/pip-FsniHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:09 INFO - Running setup.py (path:/tmp/pip-WW0Mzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:09 INFO - Running setup.py (path:/tmp/pip-N6wB12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:20:09 INFO - Running setup.py install for manifestparser 09:20:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:20:09 INFO - Running setup.py install for mozcrash 09:20:09 INFO - Running setup.py install for mozdebug 09:20:09 INFO - Running setup.py install for mozdevice 09:20:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:20:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:20:09 INFO - Running setup.py install for mozfile 09:20:09 INFO - Running setup.py install for mozhttpd 09:20:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:20:09 INFO - Running setup.py install for mozinfo 09:20:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for mozInstall 09:20:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for mozleak 09:20:10 INFO - Running setup.py install for mozlog 09:20:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for moznetwork 09:20:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for mozprocess 09:20:10 INFO - Running setup.py install for mozprofile 09:20:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for mozrunner 09:20:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:20:10 INFO - Running setup.py install for mozscreenshot 09:20:10 INFO - Running setup.py install for moztest 09:20:11 INFO - Running setup.py install for mozversion 09:20:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:20:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:20:11 INFO - Cleaning up... 09:20:11 INFO - Return code: 0 09:20:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:11 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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:11 INFO - Using env: {'DISPLAY': ':0', 09:20:11 INFO - 'HOME': '/home/cltbld', 09:20:11 INFO - 'LANG': 'en_US.UTF-8', 09:20:11 INFO - 'LANGUAGE': 'en_US:en', 09:20:11 INFO - 'LOGNAME': 'cltbld', 09:20:11 INFO - 'MAIL': '/var/mail/cltbld', 09:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:11 INFO - 'MOZ_NO_REMOTE': '1', 09:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:11 INFO - 'NO_EM_RESTART': '1', 09:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:11 INFO - 'PWD': '/builds/slave/test', 09:20:11 INFO - 'SHELL': '/bin/bash', 09:20:11 INFO - 'SHLVL': '1', 09:20:11 INFO - 'TERM': 'linux', 09:20:11 INFO - 'TMOUT': '86400', 09:20:11 INFO - 'USER': 'cltbld', 09:20:11 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:11 INFO - '_': '/tools/buildbot/bin/python'} 09:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:20:11 INFO - Running setup.py (path:/tmp/pip-0PwSNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:20:11 INFO - Running setup.py (path:/tmp/pip-o_iN7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:20:11 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)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:20:11 INFO - Running setup.py (path:/tmp/pip-viA2Pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:20:11 INFO - Running setup.py (path:/tmp/pip-C9B4G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:20:11 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)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:20:11 INFO - Running setup.py (path:/tmp/pip-mkD5q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:20:11 INFO - Running setup.py (path:/tmp/pip-CvgpbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:20:11 INFO - Running setup.py (path:/tmp/pip-DPJmZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:20:11 INFO - Running setup.py (path:/tmp/pip-fIDqZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:20:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:20:11 INFO - Running setup.py (path:/tmp/pip-WeoKRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:20:12 INFO - Running setup.py (path:/tmp/pip-h_1ojN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:20:12 INFO - Running setup.py (path:/tmp/pip-dHqezt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:20:12 INFO - Running setup.py (path:/tmp/pip-IlSeFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:20:12 INFO - Running setup.py (path:/tmp/pip-9iVELe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:20:12 INFO - Running setup.py (path:/tmp/pip-2NMNIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:20:12 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)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:12 INFO - Running setup.py (path:/tmp/pip-CLcP8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:20:12 INFO - Running setup.py (path:/tmp/pip-UQhYty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:20:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:20:12 INFO - Running setup.py (path:/tmp/pip-fLx4MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:20:12 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)) 09:20:12 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)) 09:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:20:12 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)) 09:20:12 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)) 09:20:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:20:14 INFO - Downloading blessings-1.6.tar.gz 09:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:20:14 INFO - Installing collected packages: blessings 09:20:14 INFO - Running setup.py install for blessings 09:20:14 INFO - Successfully installed blessings 09:20:14 INFO - Cleaning up... 09:20:14 INFO - Return code: 0 09:20:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:20:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:15 INFO - Reading from file tmpfile_stdout 09:20:15 INFO - Current package versions: 09:20:15 INFO - argparse == 1.2.1 09:20:15 INFO - blessings == 1.6 09:20:15 INFO - blobuploader == 1.2.4 09:20:15 INFO - docopt == 0.6.1 09:20:15 INFO - functools32 == 3.2.3-2 09:20:15 INFO - jsonschema == 2.5.1 09:20:15 INFO - manifestparser == 1.1 09:20:15 INFO - mozInstall == 1.12 09:20:15 INFO - mozcrash == 0.17 09:20:15 INFO - mozdebug == 0.1 09:20:15 INFO - mozdevice == 0.48 09:20:15 INFO - mozfile == 1.2 09:20:15 INFO - mozhttpd == 0.7 09:20:15 INFO - mozinfo == 0.9 09:20:15 INFO - mozleak == 0.1 09:20:15 INFO - mozlog == 3.4 09:20:15 INFO - moznetwork == 0.27 09:20:15 INFO - mozprocess == 0.23 09:20:15 INFO - mozprofile == 0.28 09:20:15 INFO - mozrunner == 6.12 09:20:15 INFO - mozscreenshot == 0.1 09:20:15 INFO - mozsystemmonitor == 0.3 09:20:15 INFO - moztest == 0.7 09:20:15 INFO - mozversion == 1.4 09:20:15 INFO - psutil == 3.1.1 09:20:15 INFO - requests == 1.2.3 09:20:15 INFO - wsgiref == 0.1.2 09:20:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:15 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 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:20:15 INFO - Using env: {'DISPLAY': ':0', 09:20:15 INFO - 'HOME': '/home/cltbld', 09:20:15 INFO - 'LANG': 'en_US.UTF-8', 09:20:15 INFO - 'LANGUAGE': 'en_US:en', 09:20:15 INFO - 'LOGNAME': 'cltbld', 09:20:15 INFO - 'MAIL': '/var/mail/cltbld', 09:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:15 INFO - 'MOZ_NO_REMOTE': '1', 09:20:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:15 INFO - 'NO_EM_RESTART': '1', 09:20:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:15 INFO - 'PWD': '/builds/slave/test', 09:20:15 INFO - 'SHELL': '/bin/bash', 09:20:15 INFO - 'SHLVL': '1', 09:20:15 INFO - 'TERM': 'linux', 09:20:15 INFO - 'TMOUT': '86400', 09:20:15 INFO - 'USER': 'cltbld', 09:20:15 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:15 INFO - '_': '/tools/buildbot/bin/python'} 09:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:20:15 INFO - Cleaning up... 09:20:15 INFO - Return code: 0 09:20:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:20: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')]} 09:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:20:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20: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')]} 09:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:20:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:20:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f357ee13f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f38e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, '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': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:20:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:20:15 INFO - Using env: {'DISPLAY': ':0', 09:20:15 INFO - 'HOME': '/home/cltbld', 09:20:15 INFO - 'LANG': 'en_US.UTF-8', 09:20:15 INFO - 'LANGUAGE': 'en_US:en', 09:20:15 INFO - 'LOGNAME': 'cltbld', 09:20:15 INFO - 'MAIL': '/var/mail/cltbld', 09:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:15 INFO - 'MOZ_NO_REMOTE': '1', 09:20:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:15 INFO - 'NO_EM_RESTART': '1', 09:20:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:15 INFO - 'PWD': '/builds/slave/test', 09:20:15 INFO - 'SHELL': '/bin/bash', 09:20:15 INFO - 'SHLVL': '1', 09:20:15 INFO - 'TERM': 'linux', 09:20:15 INFO - 'TMOUT': '86400', 09:20:15 INFO - 'USER': 'cltbld', 09:20:15 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:15 INFO - '_': '/tools/buildbot/bin/python'} 09:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:20:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:20:15 INFO - Cleaning up... 09:20:15 INFO - Return code: 0 09:20:15 INFO - Running post-action listener: _resource_record_post_action 09:20:15 INFO - Running post-action listener: _start_resource_monitoring 09:20:15 INFO - Starting resource monitoring. 09:20:15 INFO - [mozharness: 2016-10-17 16:20:15.481956Z] Finished create-virtualenv step (success) 09:20:15 INFO - [mozharness: 2016-10-17 16:20:15.482413Z] Running install step. 09:20:15 INFO - Running pre-action listener: _resource_record_pre_action 09:20:15 INFO - Running main action method: install 09:20:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:20:15 INFO - Reading from file tmpfile_stdout 09:20:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:20:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:20:15 INFO - Reading from file tmpfile_stdout 09:20:15 INFO - Output received: 09:20:15 INFO - Usage: mozinstall [options] installer 09:20:15 INFO - Options: 09:20:15 INFO - -h, --help show this help message and exit 09:20:15 INFO - -d DEST, --destination=DEST 09:20:15 INFO - Directory to install application into. [default: 09:20:15 INFO - "/builds/slave/test"] 09:20:15 INFO - --app=APP Application being installed. [default: firefox] 09:20:15 INFO - mkdir: /builds/slave/test/build/application 09:20:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:20:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:20:31 INFO - Reading from file tmpfile_stdout 09:20:31 INFO - Output received: 09:20:31 INFO - /builds/slave/test/build/application/firefox/firefox 09:20:31 INFO - Running post-action listener: _resource_record_post_action 09:20:31 INFO - [mozharness: 2016-10-17 16:20:31.963644Z] Finished install step (success) 09:20:31 INFO - [mozharness: 2016-10-17 16:20:31.963889Z] Running run-tests step. 09:20:31 INFO - Running pre-action listener: _resource_record_pre_action 09:20:31 INFO - Running main action method: run_tests 09:20:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:20:31 INFO - Python 2.7.3 09:20:31 INFO - Return code: 0 09:20:31 INFO - grabbing minidump binary from tooltool 09:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:20:31 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298c100>, '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 0x29839d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2983e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:20:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:20:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:20:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:20:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:20:32 INFO - Return code: 0 09:20:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:20:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:20:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:20:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:20:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:20:32 INFO - Using env: {'DISPLAY': ':0', 09:20:32 INFO - 'HOME': '/home/cltbld', 09:20:32 INFO - 'LANG': 'en_US.UTF-8', 09:20:32 INFO - 'LANGUAGE': 'en_US:en', 09:20:32 INFO - 'LOGNAME': 'cltbld', 09:20:32 INFO - 'MAIL': '/var/mail/cltbld', 09:20:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:20:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:20:32 INFO - 'MOZ_NO_REMOTE': '1', 09:20:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:20:32 INFO - 'NO_EM_RESTART': '1', 09:20:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:20:32 INFO - 'PWD': '/builds/slave/test', 09:20:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:20:32 INFO - 'SHELL': '/bin/bash', 09:20:32 INFO - 'SHLVL': '1', 09:20:32 INFO - 'TERM': 'linux', 09:20:32 INFO - 'TMOUT': '86400', 09:20:32 INFO - 'USER': 'cltbld', 09:20:32 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1476721176.308944-810990983', 09:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:20:32 INFO - '_': '/tools/buildbot/bin/python'} 09:20:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:20:32 INFO - mozversion application_buildid: 20161017061949 09:20:32 INFO - mozversion application_changeset: fcc05dd319e0479bf61387c33ca3c7051bfaeb0b 09:20:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:20:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:20:32 INFO - mozversion application_name: Firefox 09:20:32 INFO - mozversion application_remotingname: firefox-dev 09:20:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:20:32 INFO - mozversion application_vendor: Mozilla 09:20:32 INFO - mozversion application_version: 51.0a2 09:20:32 INFO - mozversion platform_buildid: 20161017061949 09:20:32 INFO - mozversion platform_changeset: fcc05dd319e0479bf61387c33ca3c7051bfaeb0b 09:20:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:20:32 INFO - mozversion platform_version: 51.0a2 09:20:32 INFO - using testdate: 1476721232 09:20:32 INFO - actual date: 1476721232 09:20:32 INFO - starting webserver on 'localhost:46295' 09:20:32 INFO - SUITE-START | Running 2 tests 09:20:32 INFO - TEST-START | dromaeo_css 09:20:32 INFO - operating with platform_type : linux_ 09:20:32 INFO - Initialising browser for dromaeo_css test... 09:20:32 INFO - TEST-INFO | started process 6125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdmI9El/profile http://localhost:46295/getInfo.html) 09:20:36 INFO - PROCESS | 6125 | __metrics Screen width/height:1600/1200 09:20:36 INFO - PROCESS | 6125 | colorDepth:24 09:20:36 INFO - PROCESS | 6125 | Browser inner width/height: 1024/697 09:20:36 INFO - PROCESS | 6125 | __metrics 09:20:36 INFO - PROCESS | 6125 | 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" 09:20:36 INFO - PROCESS | 6125 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:20:37 INFO - PROCESS | 6125 | console.error: PushService: 09:20:37 INFO - PROCESS | 6125 | stateChangeProcessEnqueue: Error transitioning state 09:20:37 INFO - PROCESS | 6125 | UnknownError 09:20:37 INFO - TEST-INFO | 6125: exit 0 09:20:37 INFO - Browser initialized. 09:20:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:20:37 INFO - TEST-INFO | started process 6215 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdmI9El/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:49 INFO - PROCESS | 6215 | RSS: Main: 144408576 09:20:49 INFO - PROCESS | 6215 | 09:21:40 INFO - PROCESS | 6215 | 1476721300842 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:23:49 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46295/tests/dromaeo/cssquery-ext.html) 09:23:51 INFO - PROCESS | 6215 | RSS: Main: 266739712 09:23:51 INFO - PROCESS | 6215 | 09:26:53 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-ext.html (next: http://localhost:46295/tests/dromaeo/cssquery-jquery.html) 09:26:54 INFO - PROCESS | 6215 | RSS: Main: 229990400 09:26:54 INFO - PROCESS | 6215 | 09:29:55 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46295/tests/dromaeo/cssquery-mootools.html) 09:29:56 INFO - PROCESS | 6215 | RSS: Main: 176799744 09:29:56 INFO - PROCESS | 6215 | 09:32:58 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46295/tests/dromaeo/cssquery-prototype.html) 09:32:59 INFO - PROCESS | 6215 | RSS: Main: 175652864 09:32:59 INFO - PROCESS | 6215 | 09:36:01 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46295/tests/dromaeo/cssquery-yui.html) 09:36:02 INFO - PROCESS | 6215 | RSS: Main: 174313472 09:36:02 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | Cycle 1(1): loaded http://localhost:46295/tests/dromaeo/cssquery-yui.html (next: http://localhost:46295/tests/dromaeo/cssquery-dojo.html) 09:39:04 INFO - PROCESS | 6215 | RSS: Main: 255295488 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | __start_tp_report 09:39:04 INFO - PROCESS | 6215 | _x_x_mozilla_page_load 09:39:04 INFO - PROCESS | 6215 | _x_x_mozilla_page_load_details 09:39:04 INFO - PROCESS | 6215 | |i|pagename|runs| 09:39:04 INFO - PROCESS | 6215 | |0;dojo.html;3117.4388610050187;3164.3829453256617;3185.506246531787;3208.0696597986584;3222.2749881276713;5474.945250547494;5582.41384654611;5595.63628364156;5602.215689803431;5634.436556344363;5510.669359838413;5541.944580554194;5569.192467092279;5569.415211402805;5593.188987596806;5043.420006699231;5059.873503162414;5097.6941383517105;5138.537531622153;5149.948500515003;4952.554270115689;5091.847244582668;5140.588752899777;5147.330846989905;5148.922766158511;4943.456219815817;5005.1491246488185;5015.924761128586;5062.924056139161;5067.417247016585;5060.316857224265;5084.694918304914;5087.567556757698;5173.586113110942;5195;4952.950470495285;5027.824026159104;5117.948820511784;5127.897442051138;5133.153029750087;5286.07493688603;5358.11591087474;5367.06076436626;5411.134218525057;5484.396716361197;5832.154337621064;5858.179854820329;5862.97068514659;5957.07665311873;5958.463738263577;5996.640201587918;5996.700164991733;5999.880002399971;6011.489023433013;6018.337982821886;5349.0639138151055;5389.6496727712965;5462.016836969344;5530.889382212414;5531.446855314436;6047.848803779941;6082.452579267887;6085.452309292096;6086.756529738849;6089.391060893875;5857.47282744555;5857.70711464425;5861.677607731615;5870.501007414332;5871.295444546682;5745.798897038583;5751.539876809845;5798.594098413155;5807.68057756819;5827.562932780059;5837.445442682938;5845.41545845412;5851.239338885917;5851.473367396955;5871.266091738533;5804.796832110856;5825.4174582542255;5856.9750293698435;5860.3260625027815;5897.174395584621;4414.602685758297;4440.644748420119;4513.81944722214;4544.886377840514;4558.270676691714;4079.7144199906315;4079.960019990005;4083.5303940047274;4097.918041639151;4122.670186385082;5576.079946808813;5626.943730562642;5628.212050312959;5631.605787594829;5631.63394379364;5756.798512052058;5806.709664516758;5848.561357898175;5910.556708246899;5977.491913187428;5623.268975033219;5625.46558076982;5626.578006649518;5626.987142314422;5661;5037.4458809530925;5045.167547354646;5050.292958985745;5055.6713813602;5083.847484575469;5873.295204575478;5894.204282421818;5958.82123536286;6063.242094738158;6088.086786981989;5918.17145599625;5929.762809487572;5932.228810254812;5935.931532324224;5937.881242375215;5987.940120598738;6061.909071364022;6071.210742603434;6077.331493535619;6078.7264573093435;5825.533957283492;5850.23946886902;5873.970630146822;5924.200232968494;5929.466348028698;5923.733431995484;5925.08161234992;5932;5935.436133567304;5949.702514874326;5615.775368985195;5831.446012628793;5839.445252700986;5843.707814609337;5845.766169353178;5638.23883775685;5744.253247077853;5783.884322313615;5814.186013958134;5937.406259374028;5828.067509198508;5830.708464576839;5839.766409343579;5844.766209351748;5845.09401042839;5668.291463567054;5906.7341969612335;5916.792912248051;5916.822495325147;5921.348651648402;5588.944110558842;5612;5630.239917611234;5677.489025987681;5765.798197063083 09:39:04 INFO - PROCESS | 6215 | |1;ext.html;580.3558050563873;632.3644737039276;644.4972921121525;661.7683810666265;663.6980174020817;46764;47884.52115478853;47934.52065479344;47980.76009619951;52361.476385236136;49345.753271233596;49838.50161498384;49897.50102498974;49943.25085123726;49966.25050624244;8415;8601.354898382615;9318.487483188423;9517.191038761714;9525.237980961507;10797;11056.060234880046;11060.889391106066;11518.654440366803;11689.824652630217;11396.886031139666;11417.486213120388;11440.19918605698;11446.084313254962;11534.192606517547;11236.381998990053;11244.493997770038;11287.661370158909;11293.661190164266;11306.321620702784;11048;11225;11232.606858759986;11234.3259404436;11235;45933.54066459326;46293.76853115747;46354.30468542974;46477.53522464766;46603.766981164874;3976.423418604315;4032.959670403288;4042.272390969624;4093.44738460307;4564.520725323827;34110.82944585286;34419.4837077444;34736;34990.47514287288;36679.63320366789;4728.219843725781;4755.262934245197;4775.832845850413;4783.593394561466;4848.17581011229;369833;370627;371794;372447;375075;63523.36476635267;63543;63918;63978.68010659917;64062.35937640564;40565.59434405677;41449.585504144576;41659.791701041904;44133;44260.55739442565;19649.214031438583;19815.801841981396;19854.40436786899;19859.30492432758;19861.702074468896;13325.466981320833;13560.59318220455;15034.473793417177;15149.166795826335;15473.845261547465;20906.790932090484;21124.894375528023;21129.26047588327;21157.894210528848;21243.46891327729;7575.583342916082;7705.9579211509;7719.34385577221;7725.8454830903065;7797.454178207471;1002.9598816047277;1048.8269435543202;1107.8670559532907;1120.4565338423645;1146.7878442488166;34282.48576271358;35155;35270.47094293588;35478.03307536947;35616.821915890774;1131.7170707323169;1162.1922763679163;1180.7756526259984;1212.3877441891789;1276.170489182039;18287.72568411475;18498.722519162224;18521.814781852278;18583.81416185821;18623.255069797055;557513;560968.5854712182;561159;561453;562058;64049;64551.35448645453;64685;64729.35270647233;64745.676271618344;1336.8997325200648;1388.7847383655549;1427.693045995116;1460.116629439174;1462.9341679624229;33833.323333533684;35729.46405803863;35985.640143598226;36075.819620901726;36216.81891590525;53924.46075539351;54469.45530544644;54488.72755636196;54562;54583.181252280454;394618;395303;395663;395967;514833;45329.320060198464;47726.76136619433;48239.75880120577;48241.035179296916;48850.511494884595;52466.05920521887;52696.209556857444;52893;52897.471025289255;54397;44834;45574.772126139156;45966.770166148955;46141.30788038249;47348.2897756527;44255.336169956834;45763.77118114388;46348.30477542772;46891.296630549885;46991.06017879691;41627.375589365576;44109.55890441054;44767.32849007333;45501;45682.771586141855;36344.636553634125;39704.80147599243;39810.80094599624;40040.199196016496;40233.79883100683;30729.846350768104;30774.026282457322;30900.53649195219;31271.218219544695;31303 09:39:04 INFO - PROCESS | 6215 | |2;jquery.html;403.95556488786247;417.6596074199527;424.1813300330362;436.45007290813584;440.71133407618004;15839.604009899758;19489.610207795835;21095;21222.575548489058;21393.251236206735;19677.70483442746;20300.695489567668;20426.69359959602;20432.591348173028;20590.176392944264;5866.325372582165;5877.588568800175;6088.269407671086;6095.786647467339;6099.69501524922;5977.701114944257;6027.9095813562835;6035.336113027563;6037.305709843358;6071.241094863142;5829.562782791287;5966.552508561854;6048.667323297217;6051.3041000284875;6054.243219597551;5967.791127310545;5977.7608895644125;5994.880102397928;6002.309734380515;6003.879922401571;5355.491228333302;5390.838274851761;5407.351117865842;5413.864653383657;5423.2949716537;20501.282455114;20573.4856628584;23033.309000730005;24602.662747039;24789.62815557768;2453.7914277286454;2506.5362907862104;2614.006677462571;2647.113217072283;3038.2404398900276;14473.855261447356;15421.845781542153;15820.76268855968;15880.682386352324;16091.758623620655;2044.1721102953327;2103.7475502939747;2110.6767543142555;2114.164904862586;2195.6596727507263;86766;87870.12129878683;88210;88424.5578772102;88586.55706721489;9014.979981706201;11193.27243729155;11201.823808500154;11312.886871131182;11676.241044332091;13981.300934953211;14666.56000319992;14667.046641968238;14675.48635797742;14691.632709182357;8335.624896879655;9070.818583628423;9161.312901532412;9201.815963680689;9320.182564399876;9012.729618111336;9053.77365565866;9099.772505687411;9272.397294175857;9295.349325547148;6993.60127974397;7130.857382852314;7158.892616610755;7181.856362872713;7201.855962880713;2037.6535988881628;2045.7749647538758;2075.73015507983;2084.6768750843644;2102.8317734581196;924.7040946897063;992.9453880036667;1063.2504084620325;1113.4877956140267;1142.0292751161446;18610.255589776258;19405.51486212828;20432.591348172955;20603.690944635844;20656.89671551663;1065.776187000723;1083.5295526200275;1092.9890701093045;1114.4483480676954;1127.3574062784135;10865.83701244482;11404.543818247343;11697.064234861191;11776;11858.16992810495;437133;453299;491897;494332;494756;84594;85279.5736021316;85442.57278713692;85973.57013214893;86561;1058.137617841472;1103.8730545987314;1107.9723006924892;1130.141092769501;1135.2734250079955;17596.648067038845;17992.280308787504;18177.18202680856;18309.267629294678;18633.90683046576;41347.379789302584;42043;42807.785961069996;43091.7845410771;43106;53998.46001540092;54257;54638;55079;57331.42668573428;8610.364030834122;9178.128077832594;9231.676891308773;9304.302177336862;9352.766180845534;13611.387487562886;13796.724065518834;14068.929655351658;14093.520180381323;14676.266186690409;12084.818727718915;12104;12367.443465043914;12398.81401778956;12425.565105221274;11877.643670689893;11907.642770716893;12058;12062.939685301517;12484.68788280264;12373.195742276723;12668.239905605693;12796;12982.480700771863;12992.740145197233;9039.954800225956;9120.863187052068;9168.220701240469;9269.212116970133;9292.210162136294;11947.701307467383;12223.144379893321;12285.938570307091;12427.31649759272;12737.363131843556 09:39:04 INFO - PROCESS | 6215 | |3;mootools.html;1433.670255841156;1515.0228102873643;1568.584325153834;1734.141599908045;1890.2439024390233;8092.676292948282;9128.178463938244;9396.859047114282;9462.526873656325;9618.615255389775;9415.576299066559;9510.716053332799;9516.381435206724;9516.524173791317;9518.714438566836;2539.5936650135936;2568.2423685012873;2570.742925707433;2572.3311938895927;2573.0479722502605;2442.1940759549393;2502.612095125249;2511.8869650865754;2514.7610976957158;2515.195137555983;2500.4874000829786;2554.131595257612;2581.90963316284;2584.6510721052605;2585.508753336869;2469.71598266199;2469.839460435075;2479.652848601197;2509.460465999819;2515.7987361011074;2491.4518805862863;2496.588062969608;2501.5122051200024;2504.8371855829314;2543.9364015899564;9176.678816241467;9455.243580513543;9528.380655257386;9543.522823858779;9569.952150239274;5708.5433165347085;5834.183214349994;5845.269341332333;5854.853628659275;5856.15085812559;3701.2597480504005;3820.2550502651993;3857.0550215197177;3870.0905287257474;3875.6318149775725;1347.7843545032747;1351.2500562187981;1355.9050866439354;1385.8336999560117;1397.1617029782049;12870.935645321808;12923.870761292455;13012.86987130126;13030.153040052366;13137.343132843414;4798.256270278112;4898.608111351084;5048.899022019613;5244.239585260192;5286.127788914863;10348.896511034793;10462.058414742709;10486.895131048745;10498.475076246157;10500.212484063726;5730.971345143247;5742.684152371576;5744.511716504144;5745.770169193185;5745.9712701437065;4422.75674837887;4646.512116227815;4829.082474329936;5290.470952904756;5377.542908852792;4121.9381709274385;4148.374245592508;4192.538820729717;4201.684873634428;4219.409282700424;1267.2523211305374;1309.155594641451;1314.5267703626687;1321.2931081871152;1333.3533236380347;1054.7837693273013;1062.7608788022787;1064.914806815453;1073.4418102586612;1098.0940723902813;6102.572819902653;6333.904991425133;6355.1738274025;6467.159269294962;6605.801825945229;526.3026489900897;538.3674182835231;1032.1639472027682;1083.160550573312;1104.9889501105047;4298.26929421999;4468.55314468557;4481;4483.0137369778595;4526.7510286933875;11442.14183936208;11856.288622682667;11891.108166887345;11903.10726695484;11916.702082448008;11496.827547586792;11531.654050378502;11684.298942063504;11723.237989530653;11783.76432471363;8720.171583699539;8746.300295976307;8855.114488551222;8862.512561809162;8881.777955551162;6287.696770913252;6562.475001999924;6570.375814297634;6584.604923704593;6624.90062649051;10179.134773544036;10582.682519524427;10818.945905270422;10908.727281818017;10996.340219586851;10502.737431564272;10729;10798.892011079788;11034.282771619819;11142.665720028412;8143.959280203561;8284.420090593601;8304.70933517324;8372.623231954454;8391.832163356577;8927.285817134483;8987.955060224656;9008;9008.144226298491;9011.414258073204;8014.118446970945;8250.3812214083;8253.463524870864;8255.42212045151;8450.11273816265;8024.478408903403;8067.233612806774;8097.433179677366;8101.797455063671;8352.41533092702;7251.927480725125;7401.0748656417945;7459.40324774006;7466.738664146729;7491.850162996819;5406.83779486616;5462.126059830408;5494.201005423008;5502.614816962774;5544.112941929272;5547.611667183259;5592.608517403743;5596.132599447093;5600.467955544217;5604.887902242014 09:39:04 INFO - PROCESS | 6215 | |4;prototype.html;338.4432608359249;363.10494630735246;364.9854005839766;365.9634036596341;371.63765328804436;5527.530159936406;5560.610757246989;5614;5634.943650563493;5651.802186923458;5238.790448382069;5467.425920278373;5522.254495643087;5547.11246200608;5573.693446860431;4348.999999999992;4381.058072514409;4393.384926110347;4400.669949753766;4434.578715022084;4375.124975004996;4392.8023238954165;4414.602685758281;4422.97788511057;4444.822207111712;4357.302831546953;4396.450443694538;4401.80191891364;4404.581564751344;4419.845305414311;4178.937315940263;4346.478422589278;4363.6072753452345;4373.497047839475;4394.121175764828;3847.807179774279;3861.575226725058;3863.690904727643;3870.806459677005;3881.8835434936996;5505.614606977513;5506.752196151143;5519.172124181405;5530.2810634617645;5533.501984821345;4920.188168952119;4997.450280469146;5008.874778130576;5037.773300201499;5053.898922021539;5799.159121927297;5815.302163740378;5818.505427038707;5862.912056319158;5866.354700982888;4183.435236243099;4200.243956087903;4227.556106608792;4262.144363276612;4306.267934451151;5716.742746576414;5788.218590490314;5843.983825580211;6020.157177995085;6040.426159514839;4212.220739163265;4332.805023773938;4347.239233134189;4350.608445239944;4368.716033457815;4416.895776055986;4424.402705634762;4424.867253982386;4428.247197976351;4430.379746835446;4748.7863046163;4794.880127996828;4907.411110666742;5008.248762685626;5047.747612619354;5834;5837.211976383134;5840.890230856124;5843.883122337529;5854.356020837705;1501.6321001354738;1531.839156888533;1534.9539513814605;1536.4391996921042;1543.3903608074747;980.7352014955922;1016.1210552871713;1021.7803172317839;1034.6068493972243;1035.4305132177271;5583.469570390806;5588.720563971785;5602.971985140048;5619.606627536115;5687.60186786921;5956.374580669054;5961.791337303173;5966.642001479925;5970.701464926737;6043.123747056652;5396.4603539646505;5704.80033198836;5706.28671416073;5709.74306156224;5718.370979192286;3172.254520187754;3198.8560514776427;3203.2952750394875;3218.211538173116;3222.726068284222;5949.613275137102;6198.349173336825;6198.6280823150755;6219.191505104398;6219.315875253718;6157.7229024694;6164.630122192682;6174.938250617436;6178.62928224308;6192.256929168529;6134.609263090887;6188.381161883775;6193.938060619426;6228.532860035425;6248.4688801452385;5950.798204664302;5972.402759723993;6005.159277701213;6029.547783916136;6056.9697151513965;5678.20505129274;5928.407159284037;5941.31674857397;5956.242908341963;5987.2815262168815;5943.227380440515;6059.909101363395;6090.634561926298;6177.4749146323065;6222.782202622887;4351.673624478113;4353.128154893427;4380.233459144701;4390.582894625006;4394.384786130008;5872.471477567039;5994.201739478226;6005.789797357072;6102.359252278625;6116.5412594056515;5841.94158058414;5851.561132915133;5875.236219291635;5876.676782776988;5885.558583106199;5643.3792282848035;5895.587308888508;5907.3797251288;5930.614510057005;5933.258342707161;5771.971140144273;5796.21751063601;5812.593118481665;5824.970875145597;5837.241158649348;5924.482095109647;5945.970270148621;5951.422872938588;5960.403959604005;5979.850503737442;5463.644863083887;5696.914546281726;5755.309362876482;5780.768769249183;5827.621204621686 09:39:04 INFO - PROCESS | 6215 | |5;yui.html;789.6367670871399;811.2536466450864;820.2330820682662;833.6540335760659;846.8567433964147;15169.393224271029;15831.366745330188;16953.745693814602;17576.560585985357;17787.733184002216;17616.207270672818;17765.7335139973;17770.289188432445;17791.466256012307;17802.732959005592;869.5391442535466;869.6217145541679;878.4202426398579;892.8794612727264;895.5253715530779;871.0375035585681;873.1007062725403;873.1792115411524;874.5233847553098;874.5802015032788;914.4513292024799;918.0956757593798;918.2241006349657;918.9770255743593;919.5080631861955;915.8946721127022;916.4638686368482;917.0691747875911;917.2936838634248;917.4174399256486;672.5023482622872;673.717038843682;673.7641825361134;673.9865202695968;674.1068084787676;14553.490627827976;14556.126632402089;14565.781513277308;14568.271586420637;14603.48887788933;15035;15237.542873713695;15727.213639318103;15828.28772705231;16008.839911600968;13143.079984401096;13371.26458044807;13597.388117534734;14053.64865878351;14211.78682319766;4135.6898232632675;4159.729617574848;4181.686373521968;4248.468941382327;4270.615644592001;90793.27620171405;92992.07007929902;96099;96279;96408.51795740977;12225.572104976283;12575.622731318075;12580.4338804754;12730.872691273154;12867;23399.7660023401;23781.881090594437;23839.284821455385;23846.16538421147;23853.761462385155;8845.4250473719;8864.955675221583;8956.193942545073;8990.910090899139;9393.718188454357;5184.637075404761;5199.090159222121;5275.788968441219;5321.787128514893;5356.491133342326;3697.482352470656;3815.73289869712;3816.675582575512;3817.9618203818163;3819.3316169670197;637.9776707815204;648.8313038609996;652.4910569755598;652.7225928980164;663.4128796015561;758.7154816943646;829.7635173975509;848.2832006954114;862.3833958719546;865.7446053414258;10452.477376131163;10574;10646.361218326925;10788.892111078945;10828.729281768019;667.813012356541;687.4740823270203;787.649495974286;793.9682412703543;841.7474757572704;4922.409310882717;5111.258867464197;5112.258722485219;5132.820351287687;5138.409082955508;129207.35396323146;130460.3476982609;131144;131568;135543;62837.68581157065;73589.26410735823;105928.47035764772;109883.45058274658;115977.4201128989;83242.58378708067;96422.51788741152;98165;98790;99798;10495.317804342692;10629.574817007233;10666.253362264568;10843.403612801372;10903.890961090288;53375.46624533705;53998.73000634972;54050.72974635102;54806.45193548014;55088.72455637696;73433.6328318355;75551;79891;81813.18186818293;81993;13988.650283742987;14193.929030355195;14240.145591264558;14276.21480818565;14464.638384040483;15651.217439128226;15943.441979531124;16373.263203156124;16401.5079547609;16670.74993875117;13537.187768733907;13898.791518127035;14242.430302788187;14740;15185;14270.928645356707;14503.1298122113;14714.337854796773;15408.84591154074;15862.531350103143;12435.316057616556;12489.375531223584;12554.309512976874;12828.615141545768;13357.465701371837;12410.503579857066;12779.552715654907;13127.868721312665;13360.131591446525;13461.326933653474;10159.492025398848;10478.423686697304;11146.219764616399;11556.479958401724;11692.824607631062 09:39:04 INFO - PROCESS | 6215 | __end_tp_report 09:39:04 INFO - PROCESS | 6215 | __start_cc_report 09:39:04 INFO - PROCESS | 6215 | _x_x_mozilla_cycle_collect,965 09:39:04 INFO - PROCESS | 6215 | __end_cc_report 09:39:04 INFO - PROCESS | 6215 | __startTimestamp1476722344985__endTimestamp 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | ------- Summary: start ------- 09:39:04 INFO - PROCESS | 6215 | Number of tests: 6 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | [#0] dojo.html Cycles:165 Average:5495.36 Median:5744.25 stddev:615.60 (10.7%) stddev-sans-first:588.55 09:39:04 INFO - PROCESS | 6215 | Values: 3117.4 3164.4 3185.5 3208.1 3222.3 5474.9 5582.4 5595.6 5602.2 5634.4 5510.7 5541.9 5569.2 5569.4 5593.2 5043.4 5059.9 5097.7 5138.5 5149.9 4952.6 5091.8 5140.6 5147.3 5148.9 4943.5 5005.1 5015.9 5062.9 5067.4 5060.3 5084.7 5087.6 5173.6 5195.0 4953.0 5027.8 5117.9 5127.9 5133.2 5286.1 5358.1 5367.1 5411.1 5484.4 5832.2 5858.2 5863.0 5957.1 5958.5 5996.6 5996.7 5999.9 6011.5 6018.3 5349.1 5389.6 5462.0 5530.9 5531.4 6047.8 6082.5 6085.5 6086.8 6089.4 5857.5 5857.7 5861.7 5870.5 5871.3 5745.8 5751.5 5798.6 5807.7 5827.6 5837.4 5845.4 5851.2 5851.5 5871.3 5804.8 5825.4 5857.0 5860.3 5897.2 4414.6 4440.6 4513.8 4544.9 4558.3 4079.7 4080.0 4083.5 4097.9 4122.7 5576.1 5626.9 5628.2 5631.6 5631.6 5756.8 5806.7 5848.6 5910.6 5977.5 5623.3 5625.5 5626.6 5627.0 5661.0 5037.4 5045.2 5050.3 5055.7 5083.8 5873.3 5894.2 5958.8 6063.2 6088.1 5918.2 5929.8 5932.2 5935.9 5937.9 5987.9 6061.9 6071.2 6077.3 6078.7 5825.5 5850.2 5874.0 5924.2 5929.5 5923.7 5925.1 5932.0 5935.4 5949.7 5615.8 5831.4 5839.4 5843.7 5845.8 5638.2 5744.3 5783.9 5814.2 5937.4 5828.1 5830.7 5839.8 5844.8 5845.1 5668.3 5906.7 5916.8 5916.8 5921.3 5588.9 5612.0 5630.2 5677.5 5765.8 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | [#1] ext.html Cycles:180 Average:63441.78 Median:4037.62 stddev:121149.84 (3000.5%) stddev-sans-first:121397.77 09:39:04 INFO - PROCESS | 6215 | Values: 580.4 632.4 644.5 661.8 663.7 46764.0 47884.5 47934.5 47980.8 52361.5 49345.8 49838.5 49897.5 49943.3 49966.3 8415.0 8601.4 9318.5 9517.2 9525.2 10797.0 11056.1 11060.9 11518.7 11689.8 11396.9 11417.5 11440.2 11446.1 11534.2 11236.4 11244.5 11287.7 11293.7 11306.3 11048.0 11225.0 11232.6 11234.3 11235.0 45933.5 46293.8 46354.3 46477.5 46603.8 3976.4 4033.0 4042.3 4093.4 4564.5 34110.8 34419.5 34736.0 34990.5 36679.6 4728.2 4755.3 4775.8 4783.6 4848.2 369833.0 370627.0 371794.0 372447.0 375075.0 63523.4 63543.0 63918.0 63978.7 64062.4 40565.6 41449.6 41659.8 44133.0 44260.6 19649.2 19815.8 19854.4 19859.3 19861.7 13325.5 13560.6 15034.5 15149.2 15473.8 20906.8 21124.9 21129.3 21157.9 21243.5 7575.6 7706.0 7719.3 7725.8 7797.5 1003.0 1048.8 1107.9 1120.5 1146.8 34282.5 35155.0 35270.5 35478.0 35616.8 1131.7 1162.2 1180.8 1212.4 1276.2 18287.7 18498.7 18521.8 18583.8 18623.3 557513.0 560968.6 561159.0 561453.0 562058.0 64049.0 64551.4 64685.0 64729.4 64745.7 1336.9 1388.8 1427.7 1460.1 1462.9 33833.3 35729.5 35985.6 36075.8 36216.8 53924.5 54469.5 54488.7 54562.0 54583.2 394618.0 395303.0 395663.0 395967.0 514833.0 45329.3 47726.8 48239.8 48241.0 48850.5 52466.1 52696.2 52893.0 52897.5 54397.0 44834.0 45574.8 45966.8 46141.3 47348.3 44255.3 45763.8 46348.3 46891.3 46991.1 41627.4 44109.6 44767.3 45501.0 45682.8 36344.6 39704.8 39810.8 40040.2 40233.8 30729.8 30774.0 30900.5 31271.2 31303.0 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | [#2] jquery.html Cycles:180 Average:29048.40 Median:13528.23 stddev:78249.67 (578.4%) stddev-sans-first:78439.63 09:39:04 INFO - PROCESS | 6215 | Values: 404.0 417.7 424.2 436.5 440.7 15839.6 19489.6 21095.0 21222.6 21393.3 19677.7 20300.7 20426.7 20432.6 20590.2 5866.3 5877.6 6088.3 6095.8 6099.7 5977.7 6027.9 6035.3 6037.3 6071.2 5829.6 5966.6 6048.7 6051.3 6054.2 5967.8 5977.8 5994.9 6002.3 6003.9 5355.5 5390.8 5407.4 5413.9 5423.3 20501.3 20573.5 23033.3 24602.7 24789.6 2453.8 2506.5 2614.0 2647.1 3038.2 14473.9 15421.8 15820.8 15880.7 16091.8 2044.2 2103.7 2110.7 2114.2 2195.7 86766.0 87870.1 88210.0 88424.6 88586.6 9015.0 11193.3 11201.8 11312.9 11676.2 13981.3 14666.6 14667.0 14675.5 14691.6 8335.6 9070.8 9161.3 9201.8 9320.2 9012.7 9053.8 9099.8 9272.4 9295.3 6993.6 7130.9 7158.9 7181.9 7201.9 2037.7 2045.8 2075.7 2084.7 2102.8 924.7 992.9 1063.3 1113.5 1142.0 18610.3 19405.5 20432.6 20603.7 20656.9 1065.8 1083.5 1093.0 1114.4 1127.4 10865.8 11404.5 11697.1 11776.0 11858.2 437133.0 453299.0 491897.0 494332.0 494756.0 84594.0 85279.6 85442.6 85973.6 86561.0 1058.1 1103.9 1108.0 1130.1 1135.3 17596.6 17992.3 18177.2 18309.3 18633.9 41347.4 42043.0 42807.8 43091.8 43106.0 53998.5 54257.0 54638.0 55079.0 57331.4 8610.4 9178.1 9231.7 9304.3 9352.8 13611.4 13796.7 14068.9 14093.5 14676.3 12084.8 12104.0 12367.4 12398.8 12425.6 11877.6 11907.6 12058.0 12062.9 12484.7 12373.2 12668.2 12796.0 12982.5 12992.7 9040.0 9120.9 9168.2 9269.2 9292.2 11947.7 12223.1 12285.9 12427.3 12737.4 09:39:04 INFO - PROCESS | 6215 | 09:39:04 INFO - PROCESS | 6215 | [#3] mootools.html Cycles:180 Average:6200.65 Median:4474.78 stddev:3482.69 (77.8%) stddev-sans-first:3474.03 09:39:05 INFO - PROCESS | 6215 | Values: 1433.7 1515.0 1568.6 1734.1 1890.2 8092.7 9128.2 9396.9 9462.5 9618.6 9415.6 9510.7 9516.4 9516.5 9518.7 2539.6 2568.2 2570.7 2572.3 2573.0 2442.2 2502.6 2511.9 2514.8 2515.2 2500.5 2554.1 2581.9 2584.7 2585.5 2469.7 2469.8 2479.7 2509.5 2515.8 2491.5 2496.6 2501.5 2504.8 2543.9 9176.7 9455.2 9528.4 9543.5 9570.0 5708.5 5834.2 5845.3 5854.9 5856.2 3701.3 3820.3 3857.1 3870.1 3875.6 1347.8 1351.3 1355.9 1385.8 1397.2 12870.9 12923.9 13012.9 13030.2 13137.3 4798.3 4898.6 5048.9 5244.2 5286.1 10348.9 10462.1 10486.9 10498.5 10500.2 5731.0 5742.7 5744.5 5745.8 5746.0 4422.8 4646.5 4829.1 5290.5 5377.5 4121.9 4148.4 4192.5 4201.7 4219.4 1267.3 1309.2 1314.5 1321.3 1333.4 1054.8 1062.8 1064.9 1073.4 1098.1 6102.6 6333.9 6355.2 6467.2 6605.8 526.3 538.4 1032.2 1083.2 1105.0 4298.3 4468.6 4481.0 4483.0 4526.8 11442.1 11856.3 11891.1 11903.1 11916.7 11496.8 11531.7 11684.3 11723.2 11783.8 8720.2 8746.3 8855.1 8862.5 8881.8 6287.7 6562.5 6570.4 6584.6 6624.9 10179.1 10582.7 10818.9 10908.7 10996.3 10502.7 10729.0 10798.9 11034.3 11142.7 8144.0 8284.4 8304.7 8372.6 8391.8 8927.3 8988.0 9008.0 9008.1 9011.4 8014.1 8250.4 8253.5 8255.4 8450.1 8024.5 8067.2 8097.4 8101.8 8352.4 7251.9 7401.1 7459.4 7466.7 7491.9 5406.8 5462.1 5494.2 5502.6 5544.1 5547.6 5592.6 5596.1 5600.5 5604.9 09:39:05 INFO - PROCESS | 6215 | 09:39:05 INFO - PROCESS | 6215 | [#4] prototype.html Cycles:180 Average:4941.56 Median:5608.49 stddev:1436.03 (25.6%) stddev-sans-first:1397.88 09:39:05 INFO - PROCESS | 6215 | Values: 338.4 363.1 365.0 366.0 371.6 5527.5 5560.6 5614.0 5634.9 5651.8 5238.8 5467.4 5522.3 5547.1 5573.7 4349.0 4381.1 4393.4 4400.7 4434.6 4375.1 4392.8 4414.6 4423.0 4444.8 4357.3 4396.5 4401.8 4404.6 4419.8 4178.9 4346.5 4363.6 4373.5 4394.1 3847.8 3861.6 3863.7 3870.8 3881.9 5505.6 5506.8 5519.2 5530.3 5533.5 4920.2 4997.5 5008.9 5037.8 5053.9 5799.2 5815.3 5818.5 5862.9 5866.4 4183.4 4200.2 4227.6 4262.1 4306.3 5716.7 5788.2 5844.0 6020.2 6040.4 4212.2 4332.8 4347.2 4350.6 4368.7 4416.9 4424.4 4424.9 4428.2 4430.4 4748.8 4794.9 4907.4 5008.2 5047.7 5834.0 5837.2 5840.9 5843.9 5854.4 1501.6 1531.8 1535.0 1536.4 1543.4 980.7 1016.1 1021.8 1034.6 1035.4 5583.5 5588.7 5603.0 5619.6 5687.6 5956.4 5961.8 5966.6 5970.7 6043.1 5396.5 5704.8 5706.3 5709.7 5718.4 3172.3 3198.9 3203.3 3218.2 3222.7 5949.6 6198.3 6198.6 6219.2 6219.3 6157.7 6164.6 6174.9 6178.6 6192.3 6134.6 6188.4 6193.9 6228.5 6248.5 5950.8 5972.4 6005.2 6029.5 6057.0 5678.2 5928.4 5941.3 5956.2 5987.3 5943.2 6059.9 6090.6 6177.5 6222.8 4351.7 4353.1 4380.2 4390.6 4394.4 5872.5 5994.2 6005.8 6102.4 6116.5 5841.9 5851.6 5875.2 5876.7 5885.6 5643.4 5895.6 5907.4 5930.6 5933.3 5772.0 5796.2 5812.6 5825.0 5837.2 5924.5 5946.0 5951.4 5960.4 5979.9 5463.6 5696.9 5755.3 5780.8 5827.6 09:39:05 INFO - PROCESS | 6215 | 09:39:05 INFO - PROCESS | 6215 | [#5] yui.html Cycles:180 Average:22636.53 Median:3817.32 stddev:32997.90 (864.4%) stddev-sans-first:33049.69 09:39:05 INFO - PROCESS | 6215 | Values: 789.6 811.3 820.2 833.7 846.9 15169.4 15831.4 16953.7 17576.6 17787.7 17616.2 17765.7 17770.3 17791.5 17802.7 869.5 869.6 878.4 892.9 895.5 871.0 873.1 873.2 874.5 874.6 914.5 918.1 918.2 919.0 919.5 915.9 916.5 917.1 917.3 917.4 672.5 673.7 673.8 674.0 674.1 14553.5 14556.1 14565.8 14568.3 14603.5 15035.0 15237.5 15727.2 15828.3 16008.8 13143.1 13371.3 13597.4 14053.6 14211.8 4135.7 4159.7 4181.7 4248.5 4270.6 90793.3 92992.1 96099.0 96279.0 96408.5 12225.6 12575.6 12580.4 12730.9 12867.0 23399.8 23781.9 23839.3 23846.2 23853.8 8845.4 8865.0 8956.2 8990.9 9393.7 5184.6 5199.1 5275.8 5321.8 5356.5 3697.5 3815.7 3816.7 3818.0 3819.3 638.0 648.8 652.5 652.7 663.4 758.7 829.8 848.3 862.4 865.7 10452.5 10574.0 10646.4 10788.9 10828.7 667.8 687.5 787.6 794.0 841.7 4922.4 5111.3 5112.3 5132.8 5138.4 129207.4 130460.3 131144.0 131568.0 135543.0 62837.7 73589.3 105928.5 109883.5 115977.4 83242.6 96422.5 98165.0 98790.0 99798.0 10495.3 10629.6 10666.3 10843.4 10903.9 53375.5 53998.7 54050.7 54806.5 55088.7 73433.6 75551.0 79891.0 81813.2 81993.0 13988.7 14193.9 14240.1 14276.2 14464.6 15651.2 15943.4 16373.3 16401.5 16670.7 13537.2 13898.8 14242.4 14740.0 15185.0 14270.9 14503.1 14714.3 15408.8 15862.5 12435.3 12489.4 12554.3 12828.6 13357.5 12410.5 12779.6 13127.9 13360.1 13461.3 10159.5 10478.4 11146.2 11556.5 11692.8 09:39:05 INFO - PROCESS | 6215 | -------- Summary: end -------- 09:39:05 INFO - PROCESS | 6215 | 09:39:05 INFO - TEST-INFO | 6215: exit 0 09:39:05 INFO - TEST-OK | dromaeo_css | took 1113439ms 09:39:05 INFO - TEST-START | kraken 09:39:05 INFO - operating with platform_type : linux_ 09:39:05 INFO - Initialising browser for kraken test... 09:39:05 INFO - TEST-INFO | started process 6531 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwdYDAg/profile http://localhost:46295/getInfo.html) 09:39:09 INFO - PROCESS | 6531 | __metrics Screen width/height:1600/1200 09:39:09 INFO - PROCESS | 6531 | colorDepth:24 09:39:09 INFO - PROCESS | 6531 | Browser inner width/height: 1024/697 09:39:09 INFO - PROCESS | 6531 | __metrics 09:39:10 INFO - PROCESS | 6531 | 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" 09:39:10 INFO - PROCESS | 6531 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:10 INFO - PROCESS | 6531 | console.error: PushService: 09:39:10 INFO - PROCESS | 6531 | stateChangeProcessEnqueue: Error transitioning state 09:39:10 INFO - PROCESS | 6531 | UnknownError 09:39:11 INFO - TEST-INFO | 6531: exit 0 09:39:11 INFO - Browser initialized. 09:39:11 INFO - Running cycle 1/1 for kraken test... 09:39:11 INFO - TEST-INFO | started process 6624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwdYDAg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:23 INFO - PROCESS | 6624 | RSS: Main: 144711680 09:39:23 INFO - PROCESS | 6624 | 09:39:25 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46295/tests/kraken/driver.html?testName=audio-beat-detection) 09:39:26 INFO - PROCESS | 6624 | RSS: Main: 185851904 09:39:26 INFO - PROCESS | 6624 | 09:39:29 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46295/tests/kraken/driver.html?testName=audio-dft) 09:39:29 INFO - PROCESS | 6624 | RSS: Main: 175652864 09:39:29 INFO - PROCESS | 6624 | 09:39:32 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46295/tests/kraken/driver.html?testName=audio-fft) 09:39:33 INFO - PROCESS | 6624 | RSS: Main: 266600448 09:39:33 INFO - PROCESS | 6624 | 09:39:35 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46295/tests/kraken/driver.html?testName=audio-oscillator) 09:39:35 INFO - PROCESS | 6624 | RSS: Main: 172818432 09:39:35 INFO - PROCESS | 6624 | 09:39:37 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46295/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:39:38 INFO - PROCESS | 6624 | RSS: Main: 174264320 09:39:38 INFO - PROCESS | 6624 | 09:39:41 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46295/tests/kraken/driver.html?testName=imaging-darkroom) 09:39:41 INFO - PROCESS | 6624 | RSS: Main: 210706432 09:39:41 INFO - PROCESS | 6624 | 09:39:45 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46295/tests/kraken/driver.html?testName=imaging-desaturate) 09:39:45 INFO - PROCESS | 6624 | RSS: Main: 209526784 09:39:45 INFO - PROCESS | 6624 | 09:39:48 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46295/tests/kraken/driver.html?testName=json-parse-financial) 09:39:49 INFO - PROCESS | 6624 | RSS: Main: 209252352 09:39:49 INFO - PROCESS | 6624 | 09:39:50 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46295/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:51 INFO - PROCESS | 6624 | RSS: Main: 171155456 09:39:51 INFO - PROCESS | 6624 | 09:39:52 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:39:53 INFO - PROCESS | 6624 | RSS: Main: 179191808 09:39:53 INFO - PROCESS | 6624 | 09:39:55 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:39:56 INFO - PROCESS | 6624 | RSS: Main: 188534784 09:39:56 INFO - PROCESS | 6624 | 09:39:58 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:39:59 INFO - PROCESS | 6624 | RSS: Main: 189534208 09:39:59 INFO - PROCESS | 6624 | 09:40:01 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:40:02 INFO - PROCESS | 6624 | RSS: Main: 192147456 09:40:02 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | Cycle 1(1): loaded http://localhost:46295/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46295/tests/kraken/driver.html?testName=ai-astar) 09:40:04 INFO - PROCESS | 6624 | RSS: Main: 242855936 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | __start_tp_report 09:40:04 INFO - PROCESS | 6624 | _x_x_mozilla_page_load 09:40:04 INFO - PROCESS | 6624 | _x_x_mozilla_page_load_details 09:40:04 INFO - PROCESS | 6624 | |i|pagename|runs| 09:40:04 INFO - PROCESS | 6624 | |0;ai-astar;112;112;112;112;114;112;111;112;112;113 09:40:04 INFO - PROCESS | 6624 | |1;audio-beat-detection;147;122;117;156;117;122;120;119;153;117 09:40:04 INFO - PROCESS | 6624 | |2;audio-dft;163;163;158;160;161;161;161;159;160;160 09:40:04 INFO - PROCESS | 6624 | |3;audio-fft;74;76;75;113;75;77;76;75;98;74 09:40:04 INFO - PROCESS | 6624 | |4;audio-oscillator;81;81;109;82;82;82;102;81;82;111 09:40:04 INFO - PROCESS | 6624 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:40:04 INFO - PROCESS | 6624 | |6;imaging-darkroom;146;147;147;146;146;147;146;147;146;146 09:40:04 INFO - PROCESS | 6624 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;96 09:40:04 INFO - PROCESS | 6624 | |8;json-parse-financial;51;49;48;49;49;49;50;48;48;50 09:40:04 INFO - PROCESS | 6624 | |9;json-stringify-tinderbox;60;60;60;60;59;58;58;60;60;60 09:40:04 INFO - PROCESS | 6624 | |10;stanford-crypto-aes;74;74;73;74;78;76;81;76;73;73 09:40:04 INFO - PROCESS | 6624 | |11;stanford-crypto-ccm;128;131;122;127;127;120;119;119;150;121 09:40:04 INFO - PROCESS | 6624 | |12;stanford-crypto-pbkdf2;153;160;152;159;157;153;152;157;157;152 09:40:04 INFO - PROCESS | 6624 | |13;stanford-crypto-sha256-iterative;65;63;64;63;61;63;64;62;61;60 09:40:04 INFO - PROCESS | 6624 | __end_tp_report 09:40:04 INFO - PROCESS | 6624 | __start_cc_report 09:40:04 INFO - PROCESS | 6624 | _x_x_mozilla_cycle_collect,1559 09:40:04 INFO - PROCESS | 6624 | __end_cc_report 09:40:04 INFO - PROCESS | 6624 | __startTimestamp1476722404405__endTimestamp 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | ------- Summary: start ------- 09:40:04 INFO - PROCESS | 6624 | Number of tests: 14 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:0.79 (0.7%) stddev-sans-first:0.83 09:40:04 INFO - PROCESS | 6624 | Values: 112.0 112.0 112.0 112.0 114.0 112.0 111.0 112.0 112.0 113.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:122.00 stddev:16.12 (13.2%) stddev-sans-first:15.73 09:40:04 INFO - PROCESS | 6624 | Values: 147.0 122.0 117.0 156.0 117.0 122.0 120.0 119.0 153.0 117.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.58 (1.0%) stddev-sans-first:1.41 09:40:04 INFO - PROCESS | 6624 | Values: 163.0 163.0 158.0 160.0 161.0 161.0 161.0 159.0 160.0 160.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.50 stddev:13.27 (17.6%) stddev-sans-first:13.81 09:40:04 INFO - PROCESS | 6624 | Values: 74.0 76.0 75.0 113.0 75.0 77.0 76.0 75.0 98.0 74.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:81.50 stddev:12.65 (15.5%) stddev-sans-first:13.06 09:40:04 INFO - PROCESS | 6624 | Values: 81.0 81.0 109.0 82.0 82.0 82.0 102.0 81.0 82.0 111.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:40:04 INFO - PROCESS | 6624 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:40:04 INFO - PROCESS | 6624 | Values: 146.0 147.0 147.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:40:04 INFO - PROCESS | 6624 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#8] json-parse-financial Cycles:10 Average:49.10 Median:49.00 stddev:0.99 (2.0%) stddev-sans-first:0.78 09:40:04 INFO - PROCESS | 6624 | Values: 51.0 49.0 48.0 49.0 49.0 49.0 50.0 48.0 48.0 50.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 09:40:04 INFO - PROCESS | 6624 | Values: 60.0 60.0 60.0 60.0 59.0 58.0 58.0 60.0 60.0 60.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.00 stddev:2.62 (3.5%) stddev-sans-first:2.74 09:40:04 INFO - PROCESS | 6624 | Values: 74.0 74.0 73.0 74.0 78.0 76.0 81.0 76.0 73.0 73.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:127.00 stddev:9.31 (7.3%) stddev-sans-first:9.86 09:40:04 INFO - PROCESS | 6624 | Values: 128.0 131.0 122.0 127.0 127.0 120.0 119.0 119.0 150.0 121.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:157.00 stddev:3.12 (2.0%) stddev-sans-first:3.21 09:40:04 INFO - PROCESS | 6624 | Values: 153.0 160.0 152.0 159.0 157.0 153.0 152.0 157.0 157.0 152.0 09:40:04 INFO - PROCESS | 6624 | 09:40:04 INFO - PROCESS | 6624 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.58 (2.5%) stddev-sans-first:1.41 09:40:04 INFO - PROCESS | 6624 | Values: 65.0 63.0 64.0 63.0 61.0 63.0 64.0 62.0 61.0 60.0 09:40:04 INFO - PROCESS | 6624 | -------- Summary: end -------- 09:40:04 INFO - PROCESS | 6624 | 09:40:05 INFO - TEST-INFO | 6624: exit 0 09:40:05 INFO - TEST-OK | kraken | took 59816ms 09:40:05 INFO - SUITE-END | took 1173s 09:40:05 INFO - Completed test suite (00:19:33) 09:40:05 INFO - javascript benchmark 09:40:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3117.4388610050187, 3164.3829453256617, 3185.506246531787, 3208.0696597986584, 3222.2749881276713, 5474.945250547494, 5582.41384654611, 5595.63628364156, 5602.215689803431, 5634.436556344363, 5510.669359838413, 5541.944580554194, 5569.192467092279, 5569.415211402805, 5593.188987596806, 5043.420006699231, 5059.873503162414, 5097.6941383517105, 5138.537531622153, 5149.948500515003, 4952.554270115689, 5091.847244582668, 5140.588752899777, 5147.330846989905, 5148.922766158511, 4943.456219815817, 5005.1491246488185, 5015.924761128586, 5062.924056139161, 5067.417247016585, 5060.316857224265, 5084.694918304914, 5087.567556757698, 5173.586113110942, 5195.0, 4952.950470495285, 5027.824026159104, 5117.948820511784, 5127.897442051138, 5133.153029750087, 5286.07493688603, 5358.11591087474, 5367.06076436626, 5411.134218525057, 5484.396716361197, 5832.154337621064, 5858.179854820329, 5862.97068514659, 5957.07665311873, 5958.463738263577, 5996.640201587918, 5996.700164991733, 5999.880002399971, 6011.489023433013, 6018.337982821886, 5349.0639138151055, 5389.6496727712965, 5462.016836969344, 5530.889382212414, 5531.446855314436, 6047.848803779941, 6082.452579267887, 6085.452309292096, 6086.756529738849, 6089.391060893875, 5857.47282744555, 5857.70711464425, 5861.677607731615, 5870.501007414332, 5871.295444546682, 5745.798897038583, 5751.539876809845, 5798.594098413155, 5807.68057756819, 5827.562932780059, 5837.445442682938, 5845.41545845412, 5851.239338885917, 5851.473367396955, 5871.266091738533, 5804.796832110856, 5825.4174582542255, 5856.9750293698435, 5860.3260625027815, 5897.174395584621, 4414.602685758297, 4440.644748420119, 4513.81944722214, 4544.886377840514, 4558.270676691714, 4079.7144199906315, 4079.960019990005, 4083.5303940047274, 4097.918041639151, 4122.670186385082, 5576.079946808813, 5626.943730562642, 5628.212050312959, 5631.605787594829, 5631.63394379364, 5756.798512052058, 5806.709664516758, 5848.561357898175, 5910.556708246899, 5977.491913187428, 5623.268975033219, 5625.46558076982, 5626.578006649518, 5626.987142314422, 5661.0, 5037.4458809530925, 5045.167547354646, 5050.292958985745, 5055.6713813602, 5083.847484575469, 5873.295204575478, 5894.204282421818, 5958.82123536286, 6063.242094738158, 6088.086786981989, 5918.17145599625, 5929.762809487572, 5932.228810254812, 5935.931532324224, 5937.881242375215, 5987.940120598738, 6061.909071364022, 6071.210742603434, 6077.331493535619, 6078.7264573093435, 5825.533957283492, 5850.23946886902, 5873.970630146822, 5924.200232968494, 5929.466348028698, 5923.733431995484, 5925.08161234992, 5932.0, 5935.436133567304, 5949.702514874326, 5615.775368985195, 5831.446012628793, 5839.445252700986, 5843.707814609337, 5845.766169353178, 5638.23883775685, 5744.253247077853, 5783.884322313615, 5814.186013958134, 5937.406259374028, 5828.067509198508, 5830.708464576839, 5839.766409343579, 5844.766209351748, 5845.09401042839, 5668.291463567054, 5906.7341969612335, 5916.792912248051, 5916.822495325147, 5921.348651648402, 5588.944110558842, 5612.0, 5630.239917611234, 5677.489025987681, 5765.798197063083], "value": 5454.053001113149, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.3558050563873, 632.3644737039276, 644.4972921121525, 661.7683810666265, 663.6980174020817, 46764.0, 47884.52115478853, 47934.52065479344, 47980.76009619951, 52361.476385236136, 49345.753271233596, 49838.50161498384, 49897.50102498974, 49943.25085123726, 49966.25050624244, 8415.0, 8601.354898382615, 9318.487483188423, 9517.191038761714, 9525.237980961507, 10797.0, 11056.060234880046, 11060.889391106066, 11518.654440366803, 11689.824652630217, 11396.886031139666, 11417.486213120388, 11440.19918605698, 11446.084313254962, 11534.192606517547, 11236.381998990053, 11244.493997770038, 11287.661370158909, 11293.661190164266, 11306.321620702784, 11048.0, 11225.0, 11232.606858759986, 11234.3259404436, 11235.0, 45933.54066459326, 46293.76853115747, 46354.30468542974, 46477.53522464766, 46603.766981164874, 3976.423418604315, 4032.959670403288, 4042.272390969624, 4093.44738460307, 4564.520725323827, 34110.82944585286, 34419.4837077444, 34736.0, 34990.47514287288, 36679.63320366789, 4728.219843725781, 4755.262934245197, 4775.832845850413, 4783.593394561466, 4848.17581011229, 369833.0, 370627.0, 371794.0, 372447.0, 375075.0, 63523.36476635267, 63543.0, 63918.0, 63978.68010659917, 64062.35937640564, 40565.59434405677, 41449.585504144576, 41659.791701041904, 44133.0, 44260.55739442565, 19649.214031438583, 19815.801841981396, 19854.40436786899, 19859.30492432758, 19861.702074468896, 13325.466981320833, 13560.59318220455, 15034.473793417177, 15149.166795826335, 15473.845261547465, 20906.790932090484, 21124.894375528023, 21129.26047588327, 21157.894210528848, 21243.46891327729, 7575.583342916082, 7705.9579211509, 7719.34385577221, 7725.8454830903065, 7797.454178207471, 1002.9598816047277, 1048.8269435543202, 1107.8670559532907, 1120.4565338423645, 1146.7878442488166, 34282.48576271358, 35155.0, 35270.47094293588, 35478.03307536947, 35616.821915890774, 1131.7170707323169, 1162.1922763679163, 1180.7756526259984, 1212.3877441891789, 1276.170489182039, 18287.72568411475, 18498.722519162224, 18521.814781852278, 18583.81416185821, 18623.255069797055, 557513.0, 560968.5854712182, 561159.0, 561453.0, 562058.0, 64049.0, 64551.35448645453, 64685.0, 64729.35270647233, 64745.676271618344, 1336.8997325200648, 1388.7847383655549, 1427.693045995116, 1460.116629439174, 1462.9341679624229, 33833.323333533684, 35729.46405803863, 35985.640143598226, 36075.819620901726, 36216.81891590525, 53924.46075539351, 54469.45530544644, 54488.72755636196, 54562.0, 54583.181252280454, 394618.0, 395303.0, 395663.0, 395967.0, 514833.0, 45329.320060198464, 47726.76136619433, 48239.75880120577, 48241.035179296916, 48850.511494884595, 52466.05920521887, 52696.209556857444, 52893.0, 52897.471025289255, 54397.0, 44834.0, 45574.772126139156, 45966.770166148955, 46141.30788038249, 47348.2897756527, 44255.336169956834, 45763.77118114388, 46348.30477542772, 46891.296630549885, 46991.06017879691, 41627.375589365576, 44109.55890441054, 44767.32849007333, 45501.0, 45682.771586141855, 36344.636553634125, 39704.80147599243, 39810.80094599624, 40040.199196016496, 40233.79883100683, 30729.846350768104, 30774.026282457322, 30900.53649195219, 31271.218219544695, 31303.0], "value": 22302.317680040334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.95556488786247, 417.6596074199527, 424.1813300330362, 436.45007290813584, 440.71133407618004, 15839.604009899758, 19489.610207795835, 21095.0, 21222.575548489058, 21393.251236206735, 19677.70483442746, 20300.695489567668, 20426.69359959602, 20432.591348173028, 20590.176392944264, 5866.325372582165, 5877.588568800175, 6088.269407671086, 6095.786647467339, 6099.69501524922, 5977.701114944257, 6027.9095813562835, 6035.336113027563, 6037.305709843358, 6071.241094863142, 5829.562782791287, 5966.552508561854, 6048.667323297217, 6051.3041000284875, 6054.243219597551, 5967.791127310545, 5977.7608895644125, 5994.880102397928, 6002.309734380515, 6003.879922401571, 5355.491228333302, 5390.838274851761, 5407.351117865842, 5413.864653383657, 5423.2949716537, 20501.282455114, 20573.4856628584, 23033.309000730005, 24602.662747039, 24789.62815557768, 2453.7914277286454, 2506.5362907862104, 2614.006677462571, 2647.113217072283, 3038.2404398900276, 14473.855261447356, 15421.845781542153, 15820.76268855968, 15880.682386352324, 16091.758623620655, 2044.1721102953327, 2103.7475502939747, 2110.6767543142555, 2114.164904862586, 2195.6596727507263, 86766.0, 87870.12129878683, 88210.0, 88424.5578772102, 88586.55706721489, 9014.979981706201, 11193.27243729155, 11201.823808500154, 11312.886871131182, 11676.241044332091, 13981.300934953211, 14666.56000319992, 14667.046641968238, 14675.48635797742, 14691.632709182357, 8335.624896879655, 9070.818583628423, 9161.312901532412, 9201.815963680689, 9320.182564399876, 9012.729618111336, 9053.77365565866, 9099.772505687411, 9272.397294175857, 9295.349325547148, 6993.60127974397, 7130.857382852314, 7158.892616610755, 7181.856362872713, 7201.855962880713, 2037.6535988881628, 2045.7749647538758, 2075.73015507983, 2084.6768750843644, 2102.8317734581196, 924.7040946897063, 992.9453880036667, 1063.2504084620325, 1113.4877956140267, 1142.0292751161446, 18610.255589776258, 19405.51486212828, 20432.591348172955, 20603.690944635844, 20656.89671551663, 1065.776187000723, 1083.5295526200275, 1092.9890701093045, 1114.4483480676954, 1127.3574062784135, 10865.83701244482, 11404.543818247343, 11697.064234861191, 11776.0, 11858.16992810495, 437133.0, 453299.0, 491897.0, 494332.0, 494756.0, 84594.0, 85279.5736021316, 85442.57278713692, 85973.57013214893, 86561.0, 1058.137617841472, 1103.8730545987314, 1107.9723006924892, 1130.141092769501, 1135.2734250079955, 17596.648067038845, 17992.280308787504, 18177.18202680856, 18309.267629294678, 18633.90683046576, 41347.379789302584, 42043.0, 42807.785961069996, 43091.7845410771, 43106.0, 53998.46001540092, 54257.0, 54638.0, 55079.0, 57331.42668573428, 8610.364030834122, 9178.128077832594, 9231.676891308773, 9304.302177336862, 9352.766180845534, 13611.387487562886, 13796.724065518834, 14068.929655351658, 14093.520180381323, 14676.266186690409, 12084.818727718915, 12104.0, 12367.443465043914, 12398.81401778956, 12425.565105221274, 11877.643670689893, 11907.642770716893, 12058.0, 12062.939685301517, 12484.68788280264, 12373.195742276723, 12668.239905605693, 12796.0, 12982.480700771863, 12992.740145197233, 9039.954800225956, 9120.863187052068, 9168.220701240469, 9269.212116970133, 9292.210162136294, 11947.701307467383, 12223.144379893321, 12285.938570307091, 12427.31649759272, 12737.363131843556], "value": 9838.670340044826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.670255841156, 1515.0228102873643, 1568.584325153834, 1734.141599908045, 1890.2439024390233, 8092.676292948282, 9128.178463938244, 9396.859047114282, 9462.526873656325, 9618.615255389775, 9415.576299066559, 9510.716053332799, 9516.381435206724, 9516.524173791317, 9518.714438566836, 2539.5936650135936, 2568.2423685012873, 2570.742925707433, 2572.3311938895927, 2573.0479722502605, 2442.1940759549393, 2502.612095125249, 2511.8869650865754, 2514.7610976957158, 2515.195137555983, 2500.4874000829786, 2554.131595257612, 2581.90963316284, 2584.6510721052605, 2585.508753336869, 2469.71598266199, 2469.839460435075, 2479.652848601197, 2509.460465999819, 2515.7987361011074, 2491.4518805862863, 2496.588062969608, 2501.5122051200024, 2504.8371855829314, 2543.9364015899564, 9176.678816241467, 9455.243580513543, 9528.380655257386, 9543.522823858779, 9569.952150239274, 5708.5433165347085, 5834.183214349994, 5845.269341332333, 5854.853628659275, 5856.15085812559, 3701.2597480504005, 3820.2550502651993, 3857.0550215197177, 3870.0905287257474, 3875.6318149775725, 1347.7843545032747, 1351.2500562187981, 1355.9050866439354, 1385.8336999560117, 1397.1617029782049, 12870.935645321808, 12923.870761292455, 13012.86987130126, 13030.153040052366, 13137.343132843414, 4798.256270278112, 4898.608111351084, 5048.899022019613, 5244.239585260192, 5286.127788914863, 10348.896511034793, 10462.058414742709, 10486.895131048745, 10498.475076246157, 10500.212484063726, 5730.971345143247, 5742.684152371576, 5744.511716504144, 5745.770169193185, 5745.9712701437065, 4422.75674837887, 4646.512116227815, 4829.082474329936, 5290.470952904756, 5377.542908852792, 4121.9381709274385, 4148.374245592508, 4192.538820729717, 4201.684873634428, 4219.409282700424, 1267.2523211305374, 1309.155594641451, 1314.5267703626687, 1321.2931081871152, 1333.3533236380347, 1054.7837693273013, 1062.7608788022787, 1064.914806815453, 1073.4418102586612, 1098.0940723902813, 6102.572819902653, 6333.904991425133, 6355.1738274025, 6467.159269294962, 6605.801825945229, 526.3026489900897, 538.3674182835231, 1032.1639472027682, 1083.160550573312, 1104.9889501105047, 4298.26929421999, 4468.55314468557, 4481.0, 4483.0137369778595, 4526.7510286933875, 11442.14183936208, 11856.288622682667, 11891.108166887345, 11903.10726695484, 11916.702082448008, 11496.827547586792, 11531.654050378502, 11684.298942063504, 11723.237989530653, 11783.76432471363, 8720.171583699539, 8746.300295976307, 8855.114488551222, 8862.512561809162, 8881.777955551162, 6287.696770913252, 6562.475001999924, 6570.375814297634, 6584.604923704593, 6624.90062649051, 10179.134773544036, 10582.682519524427, 10818.945905270422, 10908.727281818017, 10996.340219586851, 10502.737431564272, 10729.0, 10798.892011079788, 11034.282771619819, 11142.665720028412, 8143.959280203561, 8284.420090593601, 8304.70933517324, 8372.623231954454, 8391.832163356577, 8927.285817134483, 8987.955060224656, 9008.0, 9008.144226298491, 9011.414258073204, 8014.118446970945, 8250.3812214083, 8253.463524870864, 8255.42212045151, 8450.11273816265, 8024.478408903403, 8067.233612806774, 8097.433179677366, 8101.797455063671, 8352.41533092702, 7251.927480725125, 7401.0748656417945, 7459.40324774006, 7466.738664146729, 7491.850162996819, 5406.83779486616, 5462.126059830408, 5494.201005423008, 5502.614816962774, 5544.112941929272, 5547.611667183259, 5592.608517403743, 5596.132599447093, 5600.467955544217, 5604.887902242014], "value": 4973.22710494864, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.4432608359249, 363.10494630735246, 364.9854005839766, 365.9634036596341, 371.63765328804436, 5527.530159936406, 5560.610757246989, 5614.0, 5634.943650563493, 5651.802186923458, 5238.790448382069, 5467.425920278373, 5522.254495643087, 5547.11246200608, 5573.693446860431, 4348.999999999992, 4381.058072514409, 4393.384926110347, 4400.669949753766, 4434.578715022084, 4375.124975004996, 4392.8023238954165, 4414.602685758281, 4422.97788511057, 4444.822207111712, 4357.302831546953, 4396.450443694538, 4401.80191891364, 4404.581564751344, 4419.845305414311, 4178.937315940263, 4346.478422589278, 4363.6072753452345, 4373.497047839475, 4394.121175764828, 3847.807179774279, 3861.575226725058, 3863.690904727643, 3870.806459677005, 3881.8835434936996, 5505.614606977513, 5506.752196151143, 5519.172124181405, 5530.2810634617645, 5533.501984821345, 4920.188168952119, 4997.450280469146, 5008.874778130576, 5037.773300201499, 5053.898922021539, 5799.159121927297, 5815.302163740378, 5818.505427038707, 5862.912056319158, 5866.354700982888, 4183.435236243099, 4200.243956087903, 4227.556106608792, 4262.144363276612, 4306.267934451151, 5716.742746576414, 5788.218590490314, 5843.983825580211, 6020.157177995085, 6040.426159514839, 4212.220739163265, 4332.805023773938, 4347.239233134189, 4350.608445239944, 4368.716033457815, 4416.895776055986, 4424.402705634762, 4424.867253982386, 4428.247197976351, 4430.379746835446, 4748.7863046163, 4794.880127996828, 4907.411110666742, 5008.248762685626, 5047.747612619354, 5834.0, 5837.211976383134, 5840.890230856124, 5843.883122337529, 5854.356020837705, 1501.6321001354738, 1531.839156888533, 1534.9539513814605, 1536.4391996921042, 1543.3903608074747, 980.7352014955922, 1016.1210552871713, 1021.7803172317839, 1034.6068493972243, 1035.4305132177271, 5583.469570390806, 5588.720563971785, 5602.971985140048, 5619.606627536115, 5687.60186786921, 5956.374580669054, 5961.791337303173, 5966.642001479925, 5970.701464926737, 6043.123747056652, 5396.4603539646505, 5704.80033198836, 5706.28671416073, 5709.74306156224, 5718.370979192286, 3172.254520187754, 3198.8560514776427, 3203.2952750394875, 3218.211538173116, 3222.726068284222, 5949.613275137102, 6198.349173336825, 6198.6280823150755, 6219.191505104398, 6219.315875253718, 6157.7229024694, 6164.630122192682, 6174.938250617436, 6178.62928224308, 6192.256929168529, 6134.609263090887, 6188.381161883775, 6193.938060619426, 6228.532860035425, 6248.4688801452385, 5950.798204664302, 5972.402759723993, 6005.159277701213, 6029.547783916136, 6056.9697151513965, 5678.20505129274, 5928.407159284037, 5941.31674857397, 5956.242908341963, 5987.2815262168815, 5943.227380440515, 6059.909101363395, 6090.634561926298, 6177.4749146323065, 6222.782202622887, 4351.673624478113, 4353.128154893427, 4380.233459144701, 4390.582894625006, 4394.384786130008, 5872.471477567039, 5994.201739478226, 6005.789797357072, 6102.359252278625, 6116.5412594056515, 5841.94158058414, 5851.561132915133, 5875.236219291635, 5876.676782776988, 5885.558583106199, 5643.3792282848035, 5895.587308888508, 5907.3797251288, 5930.614510057005, 5933.258342707161, 5771.971140144273, 5796.21751063601, 5812.593118481665, 5824.970875145597, 5837.241158649348, 5924.482095109647, 5945.970270148621, 5951.422872938588, 5960.403959604005, 5979.850503737442, 5463.644863083887, 5696.914546281726, 5755.309362876482, 5780.768769249183, 5827.621204621686], "value": 4488.169886417103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.6367670871399, 811.2536466450864, 820.2330820682662, 833.6540335760659, 846.8567433964147, 15169.393224271029, 15831.366745330188, 16953.745693814602, 17576.560585985357, 17787.733184002216, 17616.207270672818, 17765.7335139973, 17770.289188432445, 17791.466256012307, 17802.732959005592, 869.5391442535466, 869.6217145541679, 878.4202426398579, 892.8794612727264, 895.5253715530779, 871.0375035585681, 873.1007062725403, 873.1792115411524, 874.5233847553098, 874.5802015032788, 914.4513292024799, 918.0956757593798, 918.2241006349657, 918.9770255743593, 919.5080631861955, 915.8946721127022, 916.4638686368482, 917.0691747875911, 917.2936838634248, 917.4174399256486, 672.5023482622872, 673.717038843682, 673.7641825361134, 673.9865202695968, 674.1068084787676, 14553.490627827976, 14556.126632402089, 14565.781513277308, 14568.271586420637, 14603.48887788933, 15035.0, 15237.542873713695, 15727.213639318103, 15828.28772705231, 16008.839911600968, 13143.079984401096, 13371.26458044807, 13597.388117534734, 14053.64865878351, 14211.78682319766, 4135.6898232632675, 4159.729617574848, 4181.686373521968, 4248.468941382327, 4270.615644592001, 90793.27620171405, 92992.07007929902, 96099.0, 96279.0, 96408.51795740977, 12225.572104976283, 12575.622731318075, 12580.4338804754, 12730.872691273154, 12867.0, 23399.7660023401, 23781.881090594437, 23839.284821455385, 23846.16538421147, 23853.761462385155, 8845.4250473719, 8864.955675221583, 8956.193942545073, 8990.910090899139, 9393.718188454357, 5184.637075404761, 5199.090159222121, 5275.788968441219, 5321.787128514893, 5356.491133342326, 3697.482352470656, 3815.73289869712, 3816.675582575512, 3817.9618203818163, 3819.3316169670197, 637.9776707815204, 648.8313038609996, 652.4910569755598, 652.7225928980164, 663.4128796015561, 758.7154816943646, 829.7635173975509, 848.2832006954114, 862.3833958719546, 865.7446053414258, 10452.477376131163, 10574.0, 10646.361218326925, 10788.892111078945, 10828.729281768019, 667.813012356541, 687.4740823270203, 787.649495974286, 793.9682412703543, 841.7474757572704, 4922.409310882717, 5111.258867464197, 5112.258722485219, 5132.820351287687, 5138.409082955508, 129207.35396323146, 130460.3476982609, 131144.0, 131568.0, 135543.0, 62837.68581157065, 73589.26410735823, 105928.47035764772, 109883.45058274658, 115977.4201128989, 83242.58378708067, 96422.51788741152, 98165.0, 98790.0, 99798.0, 10495.317804342692, 10629.574817007233, 10666.253362264568, 10843.403612801372, 10903.890961090288, 53375.46624533705, 53998.73000634972, 54050.72974635102, 54806.45193548014, 55088.72455637696, 73433.6328318355, 75551.0, 79891.0, 81813.18186818293, 81993.0, 13988.650283742987, 14193.929030355195, 14240.145591264558, 14276.21480818565, 14464.638384040483, 15651.217439128226, 15943.441979531124, 16373.263203156124, 16401.5079547609, 16670.74993875117, 13537.187768733907, 13898.791518127035, 14242.430302788187, 14740.0, 15185.0, 14270.928645356707, 14503.1298122113, 14714.337854796773, 15408.84591154074, 15862.531350103143, 12435.316057616556, 12489.375531223584, 12554.309512976874, 12828.615141545768, 13357.465701371837, 12410.503579857066, 12779.552715654907, 13127.868721312665, 13360.131591446525, 13461.326933653474, 10159.492025398848, 10478.423686697304, 11146.219764616399, 11556.479958401724, 11692.824607631062], "value": 8191.243577466226, "unit": "score"}], "name": "dromaeo_css", "value": 7762.792448459784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 112.0, 114.0, 112.0, 111.0, 112.0, 112.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 117.0, 156.0, 117.0, 122.0, 120.0, 119.0, 153.0, 117.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 158.0, 160.0, 161.0, 161.0, 161.0, 159.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 113.0, 75.0, 77.0, 76.0, 75.0, 98.0, 74.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 109.0, 82.0, 82.0, 82.0, 102.0, 81.0, 82.0, 111.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 48.0, 49.0, 49.0, 49.0, 50.0, 48.0, 48.0, 50.0], "value": 49.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 59.0, 58.0, 58.0, 60.0, 60.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 78.0, 76.0, 81.0, 76.0, 73.0, 73.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 131.0, 122.0, 127.0, 127.0, 120.0, 119.0, 119.0, 150.0, 121.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 160.0, 152.0, 159.0, 157.0, 153.0, 152.0, 157.0, 157.0, 152.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 63.0, 61.0, 63.0, 64.0, 62.0, 61.0, 60.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1458.9, "alertThreshold": 2.0}]} 09:40:05 INFO - Return code: 0 09:40:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:40:05 INFO - # TBPL SUCCESS # 09:40:05 INFO - Running post-action listener: _resource_record_post_action 09:40:05 INFO - [mozharness: 2016-10-17 16:40:05.714528Z] Finished run-tests step (success) 09:40:05 INFO - Running post-run listener: _resource_record_post_run 09:40:05 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2121728; Write bytes: 361451520; Read time: 468; Write time: 250520 09:40:05 INFO - TinderboxPrint: CPU usage
12.9% 09:40:05 INFO - TinderboxPrint: I/O read bytes / time
2,121,728 / 468 09:40:05 INFO - TinderboxPrint: I/O write bytes / time
361,451,520 / 250,520 09:40:05 INFO - TinderboxPrint: CPU idle
8,269.7 (87.1%) 09:40:05 INFO - TinderboxPrint: CPU user
1,183.7 (12.5%) 09:40:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:40:05 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 111788032; Read time: 0; Write time: 96096 09:40:05 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249663488; Read time: 12; Write time: 154424 09:40:06 INFO - Running post-run listener: _upload_blobber_files 09:40:06 INFO - Blob upload gear active. 09:40:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:40:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:40:06 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:40:06 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:40:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:40:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:40:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:40:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:07 INFO - (blobuploader) - INFO - Done attempting. 09:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:12 INFO - (blobuploader) - INFO - Done attempting. 09:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:40:12 INFO - (blobuploader) - INFO - Done attempting. 09:40:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:40:12 INFO - Return code: 0 09:40:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:40:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:40:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbe80ae37613c44ed5f0040a43a16a1dd0bda2d2a10b67bad2d7d4e6693afe2252a5b6b97b2a99a6eb18724d54d116f31c4a4e1939f40283dc424eef8b0270f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43a869eeec95e56d32ccb0c8e7e9c9c7ce90b2f0ca2bd7af95ce54196f079d8fb3975072da2c4268c9576352940191d75a2bce97eb05df424a24102301142879", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d452c6e10309d4e3e83fb979bef4731c82f17efff3d0ef382b0f4bd5297b0b7a72dd342c3f2af05665b71ad5e2ba00b157fdceec7c34a4e53326263a220d563"} 09:40:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:40:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:40:12 INFO - Contents: 09:40:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbe80ae37613c44ed5f0040a43a16a1dd0bda2d2a10b67bad2d7d4e6693afe2252a5b6b97b2a99a6eb18724d54d116f31c4a4e1939f40283dc424eef8b0270f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43a869eeec95e56d32ccb0c8e7e9c9c7ce90b2f0ca2bd7af95ce54196f079d8fb3975072da2c4268c9576352940191d75a2bce97eb05df424a24102301142879", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d452c6e10309d4e3e83fb979bef4731c82f17efff3d0ef382b0f4bd5297b0b7a72dd342c3f2af05665b71ad5e2ba00b157fdceec7c34a4e53326263a220d563"} 09:40:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:40:12 INFO - Copying logs to upload dir... 09:40:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:40:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.400264 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-17 09:40:12.823835) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:40:12.827337) ========= 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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 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-Aurora/sha512/cbe80ae37613c44ed5f0040a43a16a1dd0bda2d2a10b67bad2d7d4e6693afe2252a5b6b97b2a99a6eb18724d54d116f31c4a4e1939f40283dc424eef8b0270f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43a869eeec95e56d32ccb0c8e7e9c9c7ce90b2f0ca2bd7af95ce54196f079d8fb3975072da2c4268c9576352940191d75a2bce97eb05df424a24102301142879", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d452c6e10309d4e3e83fb979bef4731c82f17efff3d0ef382b0f4bd5297b0b7a72dd342c3f2af05665b71ad5e2ba00b157fdceec7c34a4e53326263a220d563"} build_url:https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011426 build_url: 'https://queue.taskcluster.net/v1/task/Is2vYGyoTReV9ic3-50-fA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cbe80ae37613c44ed5f0040a43a16a1dd0bda2d2a10b67bad2d7d4e6693afe2252a5b6b97b2a99a6eb18724d54d116f31c4a4e1939f40283dc424eef8b0270f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43a869eeec95e56d32ccb0c8e7e9c9c7ce90b2f0ca2bd7af95ce54196f079d8fb3975072da2c4268c9576352940191d75a2bce97eb05df424a24102301142879", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d452c6e10309d4e3e83fb979bef4731c82f17efff3d0ef382b0f4bd5297b0b7a72dd342c3f2af05665b71ad5e2ba00b157fdceec7c34a4e53326263a220d563"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 09:40:12.854286) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:40:12.854653) ========= 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=00204705a9daa57ed339a427000001b9-1476721176.308944-810990983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 09:40:12.941035) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:40:12.941351) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 09:40:12.941695) ========= ========= Total master_lag: 0.19 =========