builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1476807433.48 results: success (0) buildid: 20161018061722 builduid: 80b9ef562df94e9c8f8c53626d8c3df9 revision: 483691f061bd94dc9398e4d660579aa9d5e313a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.479108) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.479549) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.479838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.498626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.499016) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-18 09:17:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.1M=0s 2016-10-18 09:17:14 (40.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137821 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.650536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.650854) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016007 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:13.700488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 09:17:13.700847) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 483691f061bd94dc9398e4d660579aa9d5e313a3 --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 483691f061bd94dc9398e4d660579aa9d5e313a3 --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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-18 09:17:14,548 truncating revision to first 12 chars 2016-10-18 09:17:14,548 Setting DEBUG logging. 2016-10-18 09:17:14,548 attempt 1/10 2016-10-18 09:17:14,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/483691f061bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 09:17:15,968 unpacking tar archive at: mozilla-aurora-483691f061bd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.656370 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-18 09:17:16.370427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:16.370732) ========= script_repo_revision: 483691f061bd94dc9398e4d660579aa9d5e313a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:16.371123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:16.371382) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 09:17:16.380394) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-18 09:17:16.380654) ========= /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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:17:17 INFO - MultiFileLogger online at 20161018 09:17:17 in /builds/slave/test 09:17:17 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:17:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:17 INFO - {'append_to_log': False, 09:17:17 INFO - 'base_work_dir': '/builds/slave/test', 09:17:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:17:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:17 INFO - 'branch': 'Mozilla-Aurora', 09:17:17 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:17 INFO - 'config_files': ('talos/linux_config.py',), 09:17:17 INFO - 'default_actions': ('clobber', 09:17:17 INFO - 'read-buildbot-config', 09:17:17 INFO - 'download-and-extract', 09:17:17 INFO - 'populate-webroot', 09:17:17 INFO - 'create-virtualenv', 09:17:17 INFO - 'install', 09:17:17 INFO - 'run-tests'), 09:17:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:17 INFO - 'download_minidump_stackwalk': True, 09:17:17 INFO - 'download_symbols': 'ondemand', 09:17:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:17 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:17 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:17 INFO - 'installer_path': 'installer.exe', 09:17:17 INFO - 'log_level': 'info', 09:17:17 INFO - 'log_name': 'talos', 09:17:17 INFO - 'log_to_console': True, 09:17:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:17:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:17:17 INFO - 'opt_config_files': (), 09:17:17 INFO - 'pip_index': False, 09:17:17 INFO - 'sps_profile': False, 09:17:17 INFO - 'sps_profile_interval': 0, 09:17:17 INFO - 'suite': 'dromaeojs', 09:17:17 INFO - 'system_bits': '32', 09:17:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:17 INFO - 'title': 'talos-linux64-ix-022', 09:17:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:17 INFO - 'use_talos_json': True, 09:17:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:17 INFO - 'work_dir': 'build'} 09:17:17 INFO - [mozharness: 2016-10-18 16:17:17.323954Z] Running clobber step. 09:17:17 INFO - Running pre-action listener: _resource_record_pre_action 09:17:17 INFO - Running main action method: clobber 09:17:17 INFO - rmtree: /builds/slave/test/build 09:17:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:17 INFO - Running post-action listener: _resource_record_post_action 09:17:17 INFO - [mozharness: 2016-10-18 16:17:17.788156Z] Finished clobber step (success) 09:17:17 INFO - [mozharness: 2016-10-18 16:17:17.788255Z] Running read-buildbot-config step. 09:17:17 INFO - Running pre-action listener: _resource_record_pre_action 09:17:17 INFO - Running main action method: read_buildbot_config 09:17:17 INFO - Using buildbot properties: 09:17:17 INFO - { 09:17:17 INFO - "project": "", 09:17:17 INFO - "product": "firefox", 09:17:17 INFO - "script_repo_revision": "production", 09:17:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:17:17 INFO - "repository": "", 09:17:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:17:17 INFO - "buildid": "20161018061722", 09:17:17 INFO - "pgo_build": "True", 09:17:17 INFO - "basedir": "/builds/slave/test", 09:17:17 INFO - "buildnumber": 582, 09:17:17 INFO - "slavename": "talos-linux64-ix-022", 09:17:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:17:17 INFO - "platform": "ubuntu64_hw", 09:17:17 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:17:17 INFO - "branch": "mozilla-aurora", 09:17:17 INFO - "repo_path": "releases/mozilla-aurora", 09:17:17 INFO - "revision": "483691f061bd94dc9398e4d660579aa9d5e313a3", 09:17:17 INFO - "stage_platform": "linux64-pgo", 09:17:17 INFO - "builduid": "80b9ef562df94e9c8f8c53626d8c3df9", 09:17:17 INFO - "slavebuilddir": "test" 09:17:17 INFO - } 09:17:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:17:17 INFO - Running post-action listener: _resource_record_post_action 09:17:17 INFO - [mozharness: 2016-10-18 16:17:17.790665Z] Finished read-buildbot-config step (success) 09:17:17 INFO - [mozharness: 2016-10-18 16:17:17.790754Z] Running download-and-extract step. 09:17:17 INFO - Running pre-action listener: _resource_record_pre_action 09:17:17 INFO - Running main action method: download_and_extract 09:17:17 INFO - mkdir: /builds/slave/test/build/tests 09:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:17 INFO - trying https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:17:17 INFO - Downloading https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/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:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/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:17:18 INFO - Downloaded 1361 bytes. 09:17:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:17:18 INFO - Using the following test package requirements: 09:17:18 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:17:18 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:17:18 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:17:18 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'jsshell-linux-x86_64.zip'], 09:17:18 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:17:18 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:17:18 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:17:18 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:17:18 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:17:18 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:17:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:17:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:17:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:17:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:17:18 INFO - Fetch https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:17:18 INFO - Expected file size: 21905845 09:17:23 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:17:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:17:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:17:23 INFO - Fetch https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:17:23 INFO - Expected file size: 21905845 09:17:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:17:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:17:28 INFO - Fetch https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:17:29 INFO - Expected file size: 14414081 09:17:29 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:17:29 INFO - trying https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/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:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/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:17:30 INFO - Downloaded 63999357 bytes. 09:17:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:17:30 INFO - mkdir: /builds/slave/test/properties 09:17:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:30 INFO - Contents: 09:17:30 INFO - build_url:https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - Running post-action listener: set_extra_try_arguments 09:17:31 INFO - [mozharness: 2016-10-18 16:17:31.568450Z] Finished download-and-extract step (success) 09:17:31 INFO - [mozharness: 2016-10-18 16:17:31.568749Z] Running populate-webroot step. 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: populate_webroot 09:17:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:31 INFO - '--tppagecycles', 09:17:31 INFO - '7'], 09:17:31 INFO - 'tests': ['tsvgm']}}, 09:17:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['damp', 'tps']}, 09:17:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['damp', 'tps']}, 09:17:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['dromaeo_dom']}, 09:17:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['basic_compositor_video']}, 09:17:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['a11yr', 09:17:31 INFO - 'ts_paint', 09:17:31 INFO - 'tpaint', 09:17:31 INFO - 'sessionrestore', 09:17:31 INFO - 'sessionrestore_no_auto_restore', 09:17:31 INFO - 'tabpaint']}, 09:17:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tsvgx', 09:17:31 INFO - 'tsvgr_opacity', 09:17:31 INFO - 'tart', 09:17:31 INFO - 'tscrollx', 09:17:31 INFO - 'cart']}, 09:17:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:31 INFO - 'tsvgr_opacity', 09:17:31 INFO - 'tart', 09:17:31 INFO - 'tscrollx', 09:17:31 INFO - 'cart']}, 09:17:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s'], 09:17:31 INFO - 'tests': ['tp5o']}, 09:17:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'tests': ['tp5o']}, 09:17:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--disable-e10s', 09:17:31 INFO - '--xperf_path', 09:17:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:31 INFO - 'tests': ['tp5n']}, 09:17:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:31 INFO - 'talos_options': ['--xperf_path', 09:17:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:31 INFO - 'tests': ['tp5n']}}, 09:17:31 INFO - 'talos.zip': {'path': '', 09:17:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - [mozharness: 2016-10-18 16:17:31.590547Z] Finished populate-webroot step (success) 09:17:31 INFO - [mozharness: 2016-10-18 16:17:31.590638Z] Running create-virtualenv step. 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: create_virtualenv 09:17:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:31 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:17:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:31 INFO - Using real prefix '/usr' 09:17:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:34 INFO - Installing pip.................done. 09:17:34 INFO - Return code: 0 09:17:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:34 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:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 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:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:34 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:17:34 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:17:34 INFO - Using env: {'DISPLAY': ':0', 09:17:34 INFO - 'HOME': '/home/cltbld', 09:17:34 INFO - 'LANG': 'en_US.UTF-8', 09:17:34 INFO - 'LANGUAGE': 'en_US:en', 09:17:34 INFO - 'LOGNAME': 'cltbld', 09:17:34 INFO - 'MAIL': '/var/mail/cltbld', 09:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:34 INFO - 'MOZ_NO_REMOTE': '1', 09:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:34 INFO - 'NO_EM_RESTART': '1', 09:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:34 INFO - 'PWD': '/builds/slave/test', 09:17:34 INFO - 'SHELL': '/bin/bash', 09:17:34 INFO - 'SHLVL': '1', 09:17:34 INFO - 'TERM': 'linux', 09:17:34 INFO - 'TMOUT': '86400', 09:17:34 INFO - 'USER': 'cltbld', 09:17:34 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:34 INFO - '_': '/tools/buildbot/bin/python'} 09:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:34 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:17:34 INFO - Cleaning up... 09:17:34 INFO - Return code: 0 09:17:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:34 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:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 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:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:34 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:34 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:17:34 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:17:34 INFO - Using env: {'DISPLAY': ':0', 09:17:34 INFO - 'HOME': '/home/cltbld', 09:17:34 INFO - 'LANG': 'en_US.UTF-8', 09:17:34 INFO - 'LANGUAGE': 'en_US:en', 09:17:34 INFO - 'LOGNAME': 'cltbld', 09:17:34 INFO - 'MAIL': '/var/mail/cltbld', 09:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:34 INFO - 'MOZ_NO_REMOTE': '1', 09:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:34 INFO - 'NO_EM_RESTART': '1', 09:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:34 INFO - 'PWD': '/builds/slave/test', 09:17:34 INFO - 'SHELL': '/bin/bash', 09:17:34 INFO - 'SHLVL': '1', 09:17:34 INFO - 'TERM': 'linux', 09:17:34 INFO - 'TMOUT': '86400', 09:17:34 INFO - 'USER': 'cltbld', 09:17:34 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:34 INFO - '_': '/tools/buildbot/bin/python'} 09:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:34 INFO - Downloading/unpacking psutil>=3.1.1 09:17:34 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:17:34 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:17:34 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:17:34 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:17:34 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:17:34 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:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:36 INFO - Installing collected packages: psutil 09:17:36 INFO - Running setup.py install for psutil 09:17:36 INFO - building 'psutil._psutil_linux' extension 09:17:36 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:17:36 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:17:36 INFO - building 'psutil._psutil_posix' extension 09:17:36 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:17:37 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:17:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:37 INFO - Successfully installed psutil 09:17:37 INFO - Cleaning up... 09:17:37 INFO - Return code: 0 09:17:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:37 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:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:37 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:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:37 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 09:17:37 INFO - 'HOME': '/home/cltbld', 09:17:37 INFO - 'LANG': 'en_US.UTF-8', 09:17:37 INFO - 'LANGUAGE': 'en_US:en', 09:17:37 INFO - 'LOGNAME': 'cltbld', 09:17:37 INFO - 'MAIL': '/var/mail/cltbld', 09:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:37 INFO - 'MOZ_NO_REMOTE': '1', 09:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:37 INFO - 'NO_EM_RESTART': '1', 09:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:37 INFO - 'PWD': '/builds/slave/test', 09:17:37 INFO - 'SHELL': '/bin/bash', 09:17:37 INFO - 'SHLVL': '1', 09:17:37 INFO - 'TERM': 'linux', 09:17:37 INFO - 'TMOUT': '86400', 09:17:37 INFO - 'USER': 'cltbld', 09:17:37 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:37 INFO - '_': '/tools/buildbot/bin/python'} 09:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:37 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:17:37 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:17:37 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:17:37 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:17:37 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:17:37 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:17:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:39 INFO - Installing collected packages: mozsystemmonitor 09:17:39 INFO - Running setup.py install for mozsystemmonitor 09:17:39 INFO - Successfully installed mozsystemmonitor 09:17:39 INFO - Cleaning up... 09:17:39 INFO - Return code: 0 09:17:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17: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:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17: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:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:17:39 INFO - Using env: {'DISPLAY': ':0', 09:17:39 INFO - 'HOME': '/home/cltbld', 09:17:39 INFO - 'LANG': 'en_US.UTF-8', 09:17:39 INFO - 'LANGUAGE': 'en_US:en', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MAIL': '/var/mail/cltbld', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SHLVL': '1', 09:17:39 INFO - 'TERM': 'linux', 09:17:39 INFO - 'TMOUT': '86400', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - '_': '/tools/buildbot/bin/python'} 09:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:39 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:39 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:17:39 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:17:39 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:17:39 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:17:39 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:17:39 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:17:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:41 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:17:41 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:17:41 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:17:41 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:17:41 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:17:41 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:17:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:41 INFO - warning: no files found matching '*.txt' 09:17:41 INFO - no previously-included directories found matching 'build' 09:17:41 INFO - no previously-included directories found matching 'dist' 09:17:41 INFO - no previously-included directories found matching '.git*' 09:17:41 INFO - Installing collected packages: jsonschema, functools32 09:17:41 INFO - Running setup.py install for functools32 09:17:42 INFO - warning: no files found matching '*.txt' 09:17:42 INFO - no previously-included directories found matching 'build' 09:17:42 INFO - no previously-included directories found matching 'dist' 09:17:42 INFO - no previously-included directories found matching '.git*' 09:17:42 INFO - Successfully installed jsonschema functools32 09:17:42 INFO - Cleaning up... 09:17:42 INFO - Return code: 0 09:17:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17:42 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:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:42 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:17:42 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:17:42 INFO - Using env: {'DISPLAY': ':0', 09:17:42 INFO - 'HOME': '/home/cltbld', 09:17:42 INFO - 'LANG': 'en_US.UTF-8', 09:17:42 INFO - 'LANGUAGE': 'en_US:en', 09:17:42 INFO - 'LOGNAME': 'cltbld', 09:17:42 INFO - 'MAIL': '/var/mail/cltbld', 09:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:42 INFO - 'MOZ_NO_REMOTE': '1', 09:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:42 INFO - 'NO_EM_RESTART': '1', 09:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:42 INFO - 'PWD': '/builds/slave/test', 09:17:42 INFO - 'SHELL': '/bin/bash', 09:17:42 INFO - 'SHLVL': '1', 09:17:42 INFO - 'TERM': 'linux', 09:17:42 INFO - 'TMOUT': '86400', 09:17:42 INFO - 'USER': 'cltbld', 09:17:42 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:42 INFO - '_': '/tools/buildbot/bin/python'} 09:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:42 INFO - Cleaning up... 09:17:42 INFO - Return code: 0 09:17:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:42 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:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:42 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:42 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:17:42 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:17:42 INFO - Using env: {'DISPLAY': ':0', 09:17:42 INFO - 'HOME': '/home/cltbld', 09:17:42 INFO - 'LANG': 'en_US.UTF-8', 09:17:42 INFO - 'LANGUAGE': 'en_US:en', 09:17:42 INFO - 'LOGNAME': 'cltbld', 09:17:42 INFO - 'MAIL': '/var/mail/cltbld', 09:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:42 INFO - 'MOZ_NO_REMOTE': '1', 09:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:42 INFO - 'NO_EM_RESTART': '1', 09:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:42 INFO - 'PWD': '/builds/slave/test', 09:17:42 INFO - 'SHELL': '/bin/bash', 09:17:42 INFO - 'SHLVL': '1', 09:17:42 INFO - 'TERM': 'linux', 09:17:42 INFO - 'TMOUT': '86400', 09:17:42 INFO - 'USER': 'cltbld', 09:17:42 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:42 INFO - '_': '/tools/buildbot/bin/python'} 09:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:45 INFO - Downloading docopt-0.6.1.tar.gz 09:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:45 INFO - Running setup.py install for blobuploader 09:17:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:45 INFO - Running setup.py install for requests 09:17:45 INFO - Running setup.py install for docopt 09:17:45 INFO - Successfully installed blobuploader requests docopt 09:17:45 INFO - Cleaning up... 09:17:45 INFO - Return code: 0 09:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:45 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:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:45 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:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:45 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:45 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:17:45 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:17:45 INFO - Using env: {'DISPLAY': ':0', 09:17:45 INFO - 'HOME': '/home/cltbld', 09:17:45 INFO - 'LANG': 'en_US.UTF-8', 09:17:45 INFO - 'LANGUAGE': 'en_US:en', 09:17:45 INFO - 'LOGNAME': 'cltbld', 09:17:45 INFO - 'MAIL': '/var/mail/cltbld', 09:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:45 INFO - 'MOZ_NO_REMOTE': '1', 09:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:45 INFO - 'NO_EM_RESTART': '1', 09:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:45 INFO - 'PWD': '/builds/slave/test', 09:17:45 INFO - 'SHELL': '/bin/bash', 09:17:45 INFO - 'SHLVL': '1', 09:17:45 INFO - 'TERM': 'linux', 09:17:45 INFO - 'TMOUT': '86400', 09:17:45 INFO - 'USER': 'cltbld', 09:17:45 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:45 INFO - '_': '/tools/buildbot/bin/python'} 09:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:46 INFO - Running setup.py (path:/tmp/pip-leGqeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:46 INFO - Running setup.py (path:/tmp/pip-rr52_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:46 INFO - Running setup.py (path:/tmp/pip-Bebaf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:46 INFO - Running setup.py (path:/tmp/pip-dwgnVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:46 INFO - Running setup.py (path:/tmp/pip-DsAJ19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:46 INFO - Running setup.py (path:/tmp/pip-HPP5bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:46 INFO - Running setup.py (path:/tmp/pip-Xtuxy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:46 INFO - Running setup.py (path:/tmp/pip-cwCrkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:46 INFO - Running setup.py (path:/tmp/pip-xd9DW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:46 INFO - Running setup.py (path:/tmp/pip-F7j9eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:46 INFO - Running setup.py (path:/tmp/pip-KC7yaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:46 INFO - Running setup.py (path:/tmp/pip-iofW6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:46 INFO - Running setup.py (path:/tmp/pip-Zz9Xnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:47 INFO - Running setup.py (path:/tmp/pip-T6vskE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:47 INFO - Running setup.py (path:/tmp/pip-09wwgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:47 INFO - Running setup.py (path:/tmp/pip-tEEAc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:47 INFO - Running setup.py (path:/tmp/pip-OYIamk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:47 INFO - Running setup.py install for manifestparser 09:17:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Running setup.py install for mozcrash 09:17:47 INFO - Running setup.py install for mozdebug 09:17:47 INFO - Running setup.py install for mozdevice 09:17:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Running setup.py install for mozfile 09:17:47 INFO - Running setup.py install for mozhttpd 09:17:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:47 INFO - Running setup.py install for mozinfo 09:17:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozInstall 09:17:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozleak 09:17:48 INFO - Running setup.py install for mozlog 09:17:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for moznetwork 09:17:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozprocess 09:17:48 INFO - Running setup.py install for mozprofile 09:17:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozrunner 09:17:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:17:48 INFO - Running setup.py install for mozscreenshot 09:17:48 INFO - Running setup.py install for moztest 09:17:49 INFO - Running setup.py install for mozversion 09:17:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:17:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:17:49 INFO - Cleaning up... 09:17:49 INFO - Return code: 0 09:17:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:49 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:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:49 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:49 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:17:49 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:17:49 INFO - Using env: {'DISPLAY': ':0', 09:17:49 INFO - 'HOME': '/home/cltbld', 09:17:49 INFO - 'LANG': 'en_US.UTF-8', 09:17:49 INFO - 'LANGUAGE': 'en_US:en', 09:17:49 INFO - 'LOGNAME': 'cltbld', 09:17:49 INFO - 'MAIL': '/var/mail/cltbld', 09:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:49 INFO - 'MOZ_NO_REMOTE': '1', 09:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:49 INFO - 'NO_EM_RESTART': '1', 09:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:49 INFO - 'PWD': '/builds/slave/test', 09:17:49 INFO - 'SHELL': '/bin/bash', 09:17:49 INFO - 'SHLVL': '1', 09:17:49 INFO - 'TERM': 'linux', 09:17:49 INFO - 'TMOUT': '86400', 09:17:49 INFO - 'USER': 'cltbld', 09:17:49 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:49 INFO - '_': '/tools/buildbot/bin/python'} 09:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:49 INFO - Running setup.py (path:/tmp/pip-YnqFGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:49 INFO - Running setup.py (path:/tmp/pip-G2Uftn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:49 INFO - Running setup.py (path:/tmp/pip-6zN75a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:49 INFO - Running setup.py (path:/tmp/pip-UTqt9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:49 INFO - Running setup.py (path:/tmp/pip-544QRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:49 INFO - Running setup.py (path:/tmp/pip-q6eSSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:49 INFO - Running setup.py (path:/tmp/pip-lyIP7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:49 INFO - Running setup.py (path:/tmp/pip-mc7BLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:49 INFO - Running setup.py (path:/tmp/pip-4qkqDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:49 INFO - Running setup.py (path:/tmp/pip-mM0bqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:50 INFO - Running setup.py (path:/tmp/pip-5Ypx2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:50 INFO - Running setup.py (path:/tmp/pip-trDxIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:50 INFO - Running setup.py (path:/tmp/pip-uELPgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:50 INFO - Running setup.py (path:/tmp/pip-DEaVUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:50 INFO - Running setup.py (path:/tmp/pip-QpLbY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:50 INFO - Running setup.py (path:/tmp/pip-os9ByZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:50 INFO - Running setup.py (path:/tmp/pip-gmUHVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:52 INFO - Downloading blessings-1.6.tar.gz 09:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:17:52 INFO - Installing collected packages: blessings 09:17:52 INFO - Running setup.py install for blessings 09:17:52 INFO - Successfully installed blessings 09:17:52 INFO - Cleaning up... 09:17:52 INFO - Return code: 0 09:17:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:52 INFO - Reading from file tmpfile_stdout 09:17:52 INFO - Current package versions: 09:17:52 INFO - argparse == 1.2.1 09:17:52 INFO - blessings == 1.6 09:17:52 INFO - blobuploader == 1.2.4 09:17:52 INFO - docopt == 0.6.1 09:17:52 INFO - functools32 == 3.2.3-2 09:17:52 INFO - jsonschema == 2.5.1 09:17:52 INFO - manifestparser == 1.1 09:17:52 INFO - mozInstall == 1.12 09:17:52 INFO - mozcrash == 0.17 09:17:52 INFO - mozdebug == 0.1 09:17:52 INFO - mozdevice == 0.48 09:17:52 INFO - mozfile == 1.2 09:17:52 INFO - mozhttpd == 0.7 09:17:52 INFO - mozinfo == 0.9 09:17:52 INFO - mozleak == 0.1 09:17:52 INFO - mozlog == 3.4 09:17:52 INFO - moznetwork == 0.27 09:17:52 INFO - mozprocess == 0.23 09:17:52 INFO - mozprofile == 0.28 09:17:52 INFO - mozrunner == 6.12 09:17:52 INFO - mozscreenshot == 0.1 09:17:52 INFO - mozsystemmonitor == 0.3 09:17:52 INFO - moztest == 0.7 09:17:52 INFO - mozversion == 1.4 09:17:52 INFO - psutil == 3.1.1 09:17:52 INFO - requests == 1.2.3 09:17:52 INFO - wsgiref == 0.1.2 09:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:52 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:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:52 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:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:52 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 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:52 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:17:52 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:17:52 INFO - Using env: {'DISPLAY': ':0', 09:17:52 INFO - 'HOME': '/home/cltbld', 09:17:52 INFO - 'LANG': 'en_US.UTF-8', 09:17:52 INFO - 'LANGUAGE': 'en_US:en', 09:17:52 INFO - 'LOGNAME': 'cltbld', 09:17:52 INFO - 'MAIL': '/var/mail/cltbld', 09:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:52 INFO - 'MOZ_NO_REMOTE': '1', 09:17:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:52 INFO - 'NO_EM_RESTART': '1', 09:17:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:52 INFO - 'PWD': '/builds/slave/test', 09:17:52 INFO - 'SHELL': '/bin/bash', 09:17:52 INFO - 'SHLVL': '1', 09:17:52 INFO - 'TERM': 'linux', 09:17:52 INFO - 'TMOUT': '86400', 09:17:52 INFO - 'USER': 'cltbld', 09:17:52 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:52 INFO - '_': '/tools/buildbot/bin/python'} 09:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 INFO - Cleaning up... 09:17:53 INFO - Return code: 0 09:17:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe623afdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26368a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2703ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', '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:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:17:53 INFO - Using env: {'DISPLAY': ':0', 09:17:53 INFO - 'HOME': '/home/cltbld', 09:17:53 INFO - 'LANG': 'en_US.UTF-8', 09:17:53 INFO - 'LANGUAGE': 'en_US:en', 09:17:53 INFO - 'LOGNAME': 'cltbld', 09:17:53 INFO - 'MAIL': '/var/mail/cltbld', 09:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:53 INFO - 'MOZ_NO_REMOTE': '1', 09:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:17:53 INFO - 'NO_EM_RESTART': '1', 09:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:53 INFO - 'PWD': '/builds/slave/test', 09:17:53 INFO - 'SHELL': '/bin/bash', 09:17:53 INFO - 'SHLVL': '1', 09:17:53 INFO - 'TERM': 'linux', 09:17:53 INFO - 'TMOUT': '86400', 09:17:53 INFO - 'USER': 'cltbld', 09:17:53 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:53 INFO - '_': '/tools/buildbot/bin/python'} 09:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:17:53 INFO - Cleaning up... 09:17:53 INFO - Return code: 0 09:17:53 INFO - Running post-action listener: _resource_record_post_action 09:17:53 INFO - Running post-action listener: _start_resource_monitoring 09:17:53 INFO - Starting resource monitoring. 09:17:53 INFO - [mozharness: 2016-10-18 16:17:53.446819Z] Finished create-virtualenv step (success) 09:17:53 INFO - [mozharness: 2016-10-18 16:17:53.447337Z] Running install step. 09:17:53 INFO - Running pre-action listener: _resource_record_pre_action 09:17:53 INFO - Running main action method: install 09:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:17:53 INFO - Reading from file tmpfile_stdout 09:17:53 INFO - Detecting whether we're running mozinstall >=1.0... 09:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:17:53 INFO - Reading from file tmpfile_stdout 09:17:53 INFO - Output received: 09:17:53 INFO - Usage: mozinstall [options] installer 09:17:53 INFO - Options: 09:17:53 INFO - -h, --help show this help message and exit 09:17:53 INFO - -d DEST, --destination=DEST 09:17:53 INFO - Directory to install application into. [default: 09:17:53 INFO - "/builds/slave/test"] 09:17:53 INFO - --app=APP Application being installed. [default: firefox] 09:17:53 INFO - mkdir: /builds/slave/test/build/application 09:17:53 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:17:53 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:18:09 INFO - Reading from file tmpfile_stdout 09:18:09 INFO - Output received: 09:18:09 INFO - /builds/slave/test/build/application/firefox/firefox 09:18:09 INFO - Running post-action listener: _resource_record_post_action 09:18:09 INFO - [mozharness: 2016-10-18 16:18:09.707288Z] Finished install step (success) 09:18:09 INFO - [mozharness: 2016-10-18 16:18:09.707533Z] Running run-tests step. 09:18:09 INFO - Running pre-action listener: _resource_record_pre_action 09:18:09 INFO - Running main action method: run_tests 09:18:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:09 INFO - Python 2.7.3 09:18:09 INFO - Return code: 0 09:18:09 INFO - grabbing minidump binary from tooltool 09:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:09 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 0x2700f60>, '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 0x2701d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27021d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:09 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:18:09 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:18:09 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:18:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:09 INFO - Return code: 0 09:18:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:18:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:18:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:09 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/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:18:09 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/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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:18:09 INFO - Using env: {'DISPLAY': ':0', 09:18:09 INFO - 'HOME': '/home/cltbld', 09:18:09 INFO - 'LANG': 'en_US.UTF-8', 09:18:09 INFO - 'LANGUAGE': 'en_US:en', 09:18:09 INFO - 'LOGNAME': 'cltbld', 09:18:09 INFO - 'MAIL': '/var/mail/cltbld', 09:18:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:09 INFO - 'MOZ_NO_REMOTE': '1', 09:18:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:09 INFO - 'NO_EM_RESTART': '1', 09:18:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:09 INFO - 'PWD': '/builds/slave/test', 09:18:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:09 INFO - 'SHELL': '/bin/bash', 09:18:09 INFO - 'SHLVL': '1', 09:18:09 INFO - 'TERM': 'linux', 09:18:09 INFO - 'TMOUT': '86400', 09:18:09 INFO - 'USER': 'cltbld', 09:18:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764', 09:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:09 INFO - '_': '/tools/buildbot/bin/python'} 09:18:09 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/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:18:09 INFO - mozversion application_buildid: 20161018061722 09:18:09 INFO - mozversion application_changeset: 483691f061bd94dc9398e4d660579aa9d5e313a3 09:18:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:18:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:09 INFO - mozversion application_name: Firefox 09:18:09 INFO - mozversion application_remotingname: firefox-dev 09:18:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:18:09 INFO - mozversion application_vendor: Mozilla 09:18:09 INFO - mozversion application_version: 51.0a2 09:18:09 INFO - mozversion platform_buildid: 20161018061722 09:18:09 INFO - mozversion platform_changeset: 483691f061bd94dc9398e4d660579aa9d5e313a3 09:18:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:18:09 INFO - mozversion platform_version: 51.0a2 09:18:09 INFO - using testdate: 1476807489 09:18:09 INFO - actual date: 1476807489 09:18:09 INFO - starting webserver on 'localhost:48631' 09:18:09 INFO - SUITE-START | Running 2 tests 09:18:09 INFO - TEST-START | dromaeo_css 09:18:09 INFO - operating with platform_type : linux_ 09:18:09 INFO - Initialising browser for dromaeo_css test... 09:18:09 INFO - TEST-INFO | started process 3917 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnF6OhN/profile http://localhost:48631/getInfo.html) 09:18:14 INFO - PROCESS | 3917 | __metrics Screen width/height:1600/1200 09:18:14 INFO - PROCESS | 3917 | colorDepth:24 09:18:14 INFO - PROCESS | 3917 | Browser inner width/height: 1024/697 09:18:14 INFO - PROCESS | 3917 | __metrics 09:18:14 INFO - PROCESS | 3917 | 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:18:14 INFO - PROCESS | 3917 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:18:15 INFO - PROCESS | 3917 | console.error: PushService: 09:18:15 INFO - PROCESS | 3917 | stateChangeProcessEnqueue: Error transitioning state 09:18:15 INFO - PROCESS | 3917 | UnknownError 09:18:15 INFO - TEST-INFO | 3917: exit 0 09:18:15 INFO - Browser initialized. 09:18:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:15 INFO - TEST-INFO | started process 4007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnF6OhN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:27 INFO - PROCESS | 4007 | RSS: Main: 144519168 09:18:27 INFO - PROCESS | 4007 | 09:19:18 INFO - PROCESS | 4007 | 1476807558896 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:21:28 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48631/tests/dromaeo/cssquery-ext.html) 09:21:29 INFO - PROCESS | 4007 | RSS: Main: 252706816 09:21:29 INFO - PROCESS | 4007 | 09:24:31 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-ext.html (next: http://localhost:48631/tests/dromaeo/cssquery-jquery.html) 09:24:32 INFO - PROCESS | 4007 | RSS: Main: 236421120 09:24:32 INFO - PROCESS | 4007 | 09:27:33 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48631/tests/dromaeo/cssquery-mootools.html) 09:27:34 INFO - PROCESS | 4007 | RSS: Main: 218755072 09:27:34 INFO - PROCESS | 4007 | 09:30:36 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48631/tests/dromaeo/cssquery-prototype.html) 09:30:37 INFO - PROCESS | 4007 | RSS: Main: 231079936 09:30:37 INFO - PROCESS | 4007 | 09:33:39 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48631/tests/dromaeo/cssquery-yui.html) 09:33:40 INFO - PROCESS | 4007 | RSS: Main: 175980544 09:33:40 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | Cycle 1(1): loaded http://localhost:48631/tests/dromaeo/cssquery-yui.html (next: http://localhost:48631/tests/dromaeo/cssquery-dojo.html) 09:36:43 INFO - PROCESS | 4007 | RSS: Main: 266579968 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | __start_tp_report 09:36:43 INFO - PROCESS | 4007 | _x_x_mozilla_page_load 09:36:43 INFO - PROCESS | 4007 | _x_x_mozilla_page_load_details 09:36:43 INFO - PROCESS | 4007 | |i|pagename|runs| 09:36:43 INFO - PROCESS | 4007 | |0;dojo.html;3097.1173215633544;3133.733632641226;3137.513685378766;3151.5430262611935;3180.634227063887;5452.9727351363235;5515.117581187006;5529.778808847641;5535.446455354472;5567.220589117522;5606.327240731118;5670.177824215486;5674.262345895038;5676.858078548038;5680.857978550538;5065.569426598734;5093.516115968977;5127.307813445174;5137;5167.948320516784;4945.7774400152075;4962.851114466572;5117.744112794364;5171.482851714836;5171.87070323243;4996.1756310208775;5011.974940125294;5023.2214006829;5040.420351659551;5063.392392912856;5075.06111369396;5090.516400941923;5149.356330458692;5154.201098829687;5188.092083885343;4875.58557522611;4881.194602890519;5024.497550244982;5045.924311135336;5121.052605268;5482.91775623366;5488.25908502355;5499.312585926759;5507.972460137674;5527.364353099365;5953.791617293417;5987.760489580455;6045.546584006217;6048.8185354439;6049.213602231725;5704.258446401983;5796.217510636052;6052;6073.696315184223;6086.1783659205885;5554.50009499143;5586.413426590231;5592.161175823659;5607.887842243133;5650.237217975603;6140.754369825247;6148.415900489446;6152.169457123231;6155.692215389213;6156.661383623944;5632.802851900164;5701.344345400249;5912.733926973297;5915.70421478933;5918.556108291896;5891.086881533369;5936.5250779937505;5940.910886336708;5942.732577034044;6008.579399442085;5937;5947.04847224442;5947.1376650385455;5947.226860508193;5951.4941229995065;5815.73829177688;5889.705514724246;5956.076808094752;5959.761609535657;5975.103734439869;4534.251848445036;4540;4559.566841150086;4560.498345182027;4637.049404871993;4051.47330846988;4068.2270368630457;4071.0229544909007;4072;4095.6313931746286;5680.233168522279;5683.914741278884;5686.630369026;5690.516306113944;5708.400617935057;5827.679477628686;5886.793962211302;5905.202797622352;5939.792107276225;5956.940430595725;5691.288588926384;5691.630044047207;5697.715114244271;5702.344230413472;5724.398938111522;5178.275041494194;5187.429382767892;5192.766325515361;5200;5220.3735551734035;5879.353271140171;5990.9700451497465;6080.270367555921;6122.5811271886105;6173.84565385869;6009.699515024318;6010.759569617167;6024.789132380346;6034.336223015553;6057.969710151421;6195.96902015487;6196.566240363311;6200.56596038291;6201.751929922752;6212.161358216583;5621.915671264853;5635.633683810539;5827.271591051119;5947.405259474018;5949.940500594938;5984.341722410444;6022.427869352405;6030.75876964934;6069.02895536712;6071.210742603434;5792.82621521355;5873.9412605873395;5877.735501902339;5898.64608123514;5904.763809447574;5598.160275958476;5721.084626459747;5875.764969401176;5883.323417807007;5886.705664716833;5782.161586570008;5802.941970580409;5813.418658134153;5844.824655260349;5885.705714714333;5915.615484993462;5932.762689492372;5947.3457919627945;5948.40515948419;5954.255718035246;5626.099824028136;5649.293838270216;5668.5465162787705;5820.447057529528;5837.29952405714 09:36:43 INFO - PROCESS | 4007 | |1;ext.html;566.1065583736594;584.330941072472;600.2976517474556;626.2922897126247;634.752446545847;32867.83566082169;45045.32432013522;45860.77069614656;45873;46052.309215361754;46950;47289.52710472903;47327.52672473274;47338.76330618342;47406.762966185204;8233;8424.789380265482;9041.050689677571;9197.172254497094;9204.631814727403;10917.344959302467;11132.888671113307;11144.164187685916;11551.65345039646;11563.132765042614;11297.039751621121;11399.202055856093;11402.71493212668;11406.828897566544;11408.600698975537;11127.944360278147;11139.832902506469;11180.608678696288;11203.327800331925;11204.21570490062;10715.303505272132;10731.302465339806;10796.622118225903;10803.83794243087;10820.67537973862;44238.33642495365;44730.776346118386;44741.55258447406;44800.77599612014;44865;3968.5634580196156;3977.363621820524;3986.2824691555506;4012.3379642358977;4281.036766727492;33976.6602333976;34075.318493630235;34151.31697366039;34450.483242751376;35062.47406288908;4586.151561961048;4614.169449499089;4623.398958135454;4702.788374523155;4717.386739723848;347431;350311;350583;351123;351481.99999999744;60661;60937.39062609406;60959.39040609626;61019.38980610137;61046;40237.798811005756;40879;40965.79517102396;43068;64064.67967660132;18883.716744248846;19040.71438928417;19048.238070477026;19048.52378690544;19051.523711907314;12656.74686506283;12761.872381276255;13801;14206.786898196535;14595.197264150574;20210.494737631678;20250.392488225378;20328.9835508224;20398.184072637225;20451.772893626134;6989.30106989297;7041.40148087408;7106.289371062956;7119.935218786494;7518.849623007509;907.4056492997097;947.4220725357526;961.778790878102;969.1229437359201;982.258394911837;30884.227894302374;31902;32262.516062259085;32265.838670806497;32753.83623081917;1062.9848494687556;1065.760203954104;1070.839374093892;1112.7218195451137;1118.3066498770813;16995.83004169967;17344.30622775075;17344.392946246822;18239.908800455913;18295.908520457313;563646;567076;567792;568864;570364;50872.23691644628;51337.48662513402;51341;51473.48526514762;51496.74251628718;1187.7386974865515;1289.2457912121326;1346.172104155937;1390.6245313765228;1469.963250918757;30864.074077777703;31443.842780785948;32656;32857.50713739343;32966.83516582402;53029.46970530245;53707.731461342446;53727.73136134294;53761.19358209552;53811.46188538221;417637;419207;421804;421804;423449;30587.2353191172;32112.197195070312;32120.35759284848;33754;33802.3239535203;47696.28455573239;50347.74826125846;50508.49491505185;51490.48509514857;51678.74160629323;42858.57141428671;43219.119060783014;43896;44881.32678009767;45303;43372;44072;44238.77880610705;45628.771856140505;46050.769746151054;40204.39693404543;40293.1941361177;41188.58811411967;41301.586984129775;41809.37285940774;37540;39162.412563811;39482.60517394789;40077;40211;27794.44411111726;27905.302367440978;28292;28486.287842804097;28570.857145714137 09:36:43 INFO - PROCESS | 4007 | |2;jquery.html;388.4309486602127;432.5134223998002;432.6949500602076;433.0862270776433;444.1317224825469;16235.512934611967;19255.422337329885;21734;21821.345359639196;21987.780122198772;19891.801081989175;20230.595388092264;20348.796512034874;20605.587888242226;20828.58342833146;5793.4206579341935;5808.680522571257;6035.969820150893;6042.939570604303;6052;5902.055671092626;5928.377520360344;5937.346891841894;5938.940610593903;6007.609505382158;5686.800961966332;5843.824685259449;5894.17481552583;5896.204012458327;5900.498457631085;5883;5896.351401345849;5915.112733090028;5918.260217472816;5930.555208359368;5242.79028838846;5357.732113394353;5361.410244873061;5446.455354464561;5520.227168196456;14183.503577374742;14356.210408427532;14381.64045898861;14497.710045799131;14610.488632897797;2364.869932153731;2422.4912768318673;2448.4735781806817;2859.4852926473227;2944.528875379929;10368;10612.289700041916;10813.891861081367;11094.057005154571;11208.327500349924;1970.3103913630114;2001.7798042215345;2037.2971324892887;2042.8263597594223;2050.5693804301113;84394.57802710947;86596;86600.56699716524;86847.56576217142;87736.68394974145;10421.186279704438;11375.544978200945;11600.535978560763;11760.294382337088;11979;13907.235102069484;15126.924365378323;15139.167345795866;15247.923760381349;15771.526854194393;8037.839243215103;8205.671773129128;8347.332213422911;8394.370422218359;8490.915090849136;7977.122516523178;7984.121746607869;8005.639746211435;8161.632726527202;8191.017077950565;5009.273655320031;5068.214426763784;5116.641835071509;5220.712860792617;5223.373195216598;1932.468571142947;2002.549426379053;2035.5928814236916;2058.310465993879;2081.7501899771823;843.3421930893912;888.4669198480859;942.7595963029383;949.3734135470694;975.2539307429822;17885;18316.725249121275;19206;19512.219511219675;20590.073446694936;820.9958950205211;864.4424346296604;867.5011868175824;875.0330884372804;875.7831043525405;9852.95073524642;11129.610463633733;11402.600908968147;11473.827892581618;11542.307461552333;500233;502336;508373;536867;537062;56924.71537642285;89362.55318723364;90391.54804225937;92968.53515732379;105617.47191264149;502.68833323339766;523.3667262612206;524.5803357314134;525.1518797142587;527.0302643136723;10717.410542420243;11000.669979900616;11292.209545332076;11431.199816012799;11461.713457163638;39595.406068909564;41837.1632567353;42406;43421.56578434175;46943.29585056158;57257.71371143118;58548.70725646344;61792.69103654453;62785.68607156935;63471;8865.335099867407;8981.640734370814;9072.863907041532;9092.318076144184;9165.633374664938;12930.288834114213;13067.411966461343;13138.211707297593;13388.799168012682;13932.30338483092;5519.779208831602;5709.7145142741865;11432.771344573228;11510.04329264044;11701.590444334408;8356.665733370597;8704.477731336141;11851.940740296588;11999.940000299943;12271.263724176579;8233.300169485661;8352.206540378655;8362.372822038493;8728.869066964127;9117.77205569866;11166.218364714392;12101.455434505644;12139.939300303795;12845.486180552673;12872.871271287542;11706.180567360203;11804.114691398352;11846.170768046153;11881.346525941157;12068 09:36:43 INFO - PROCESS | 4007 | |3;mootools.html;1386.7503849307134;1448.232436808492;1460.430432131469;1498.4980031689388;1533.4173014254584;8026.598670066495;8959.776005599862;9315;9325.487098209604;9608.663696770614;9387.389819661734;9444.48055356955;9464.479453630047;9490.335676502647;9501.14489695929;2491.563976304144;2518.357818756215;2519.1938579654516;2520.155747824481;2523.079076137206;2438.329459398662;2481.727010028895;2482.1932871816643;2485.6023036314195;2488.191337815208;2433.051110067075;2446.229437727119;2486;2487.9129230476938;2490.0413340863806;2371.9525609487887;2380.821438392119;2400.3999000249937;2431.8540887546806;2432.6107822748277;2334.428583537337;2375.0499800079933;2375.2161786610377;2376.3940195250275;2420.0198919437657;9160.083991600854;9268.768280792967;9278.721638350793;9332.48671323077;9336.719898403058;5490.396056433789;5701.6864072476;5707.258056452634;5709.600327977043;5714.371419143891;3607.9458808117897;3639.1448009717697;3649.908752281188;3672.889813305605;3771.830267637963;1211.987880121205;1216.4343580235145;1224.8836360545733;1232.796588562886;1262.4192871279222;12688.74622507554;12787.872121278855;12805.359732013361;12852.293123878182;12896.161749486348;4509.6843220974215;4678.157931572282;4797.664163508521;4812.711237325771;4819.084373969004;10205.846912296322;10210.132138768287;10210.846837297446;10214.182865370753;10217.233707471969;5457.617966742369;5472.398036216013;5482.671039737628;5500;5506.972465137648;4262.6376757975295;4535.954640453553;4654.930176047362;4662.300654901724;5141.511556402136;4064.695147863922;4160.502219201086;4164.501299220504;4239.491261048694;4258.233517966796;1239.6838806104386;1258.6979125009932;1263.9810402843964;1269.1940617707821;1273.6943133647858;807.3460496997451;849.609179777297;882.2544949517647;905.1536813079791;927.1933417926313;6283.340249273851;6340.302566717657;6387.680615969183;6437.227532696106;6599.1091202687985;945.2957546627806;966.7583104223944;1000.6447711062468;1025.733309339577;1036.512838965685;4352.129574085196;4359.738415695068;4386.539413361551;4418.403515525427;4430.734155950653;11477.483513241925;11713.88286117128;11848.70378240551;11849.644510664693;11852.407379630984;11345.262557933707;11705.122115841346;11710.70723231909;11714.648560543197;11811.350375729417;8679;8723.47659140441;8820.382573219813;8834.249088827522;8841.027486976427;6160.691965401622;6234.628381756006;6503.284638689645;6511.38141876521;6569;10718.35689858611;10805.621803236849;11030.393328367018;11086.778264434504;11091.223614346918;10883.455827208767;11086.722831929266;11123.110151187724;11146.944265278622;11178.832317515407;7641.312281894656;7691.269329413697;7758;7759.650815713419;7761.844763104819;9091.090890910857;9186.954065229631;9202.493862837608;9203.171714545722;9225.261979041527;8117.715879944174;8148.0598438338575;8212.46618969765;8218.137095605114;8391.580420978804;7499.962500187464;7617.54294742295;7629.351505122127;7681.807954801175;7834.373250139861;7376.704931802882;7478.775636730907;7485.401167906663;7488.625568721651;7495.287947644965;5218.347706536683;5218.373795144607;5219;5301.3373328333955;5483.150111732688;5532.806351777829;5533.114701647717;5604.85987850307;5647.463490968352;5691.772329106955 09:36:43 INFO - PROCESS | 4007 | |4;prototype.html;338.62581847059;345.88585766696986;351.68172803612737;353.78065599328414;355.51117213831;5500.614956953015;5530.723463826807;5548.250986116873;5564.8330550083565;5598.300212473441;5167.379914410276;5310.36275646922;5312.203169524573;5416.214648875911;5450.236966824647;4286.39990401344;4302.182585308797;4305.461817272833;4308.633766129883;4310.892227694301;4301.483821941371;4306.181825453154;4314.115606300701;4321.438213032301;4328.458942632171;4092.4653255029366;4227.4292970448905;4245.787710614472;4247.532771395144;4249.575042495741;4230.471191101113;4239.7032207745315;4241.427407300006;4244.278472659656;4247.638950689196;3738.813059347022;3744.307303148899;3745.3445647011663;3747.362948298796;3747.868824591126;5210.869728256786;5254.395744489394;5322.840314790563;5327.440618735016;5392.838214853561;4854.660173787836;4857.68425052374;4860.708357498526;4868.659193856432;4883.584895283905;5703.545595873067;5708.628939118944;5723.828285151452;5727.3986231445515;5751.884962300773;3934.4885164928664;4149.792510374469;4151.841042657801;4160.5007399112;4165.83336666533;5958.493528050121;5982.850428739316;5985.311689155803;5986.73059712314;6000.879982400371;4224.809883555247;4227.408162857202;4237.449131612932;4264.893377665583;4265.786710664454;4119.938200926988;4288.571142885687;4305.440292761921;4308.246056940023;4317.11499142675;4850.854474365775;4870.636221857814;4878.097551952829;4889.584385327287;4898.338724272249;5743.4543718346695;5787.913181302284;5795.217645617872;5845.509395104221;5883.146943693226;1413.2439145057344;1454.1275234859;1471.5805995291291;1486.6134804950575;1489.143742348154;913.4336711239074;919.7516670499061;933.874681009382;936.2089034765612;945.8486642137362;4867.829625963074;4868.758466590996;4878.804847806119;4901;4944.714374262646;5134.383873935151;5167.114003388811;5178.016176926447;5201.349831271091;5219.8434046978655;4917.852464426073;4941.827036053793;4959.231319145538;4981.92888528968;5231.8168864089575;3107.425126351632;3137.5921130252923;3143.905682829519;3149;3151.7865621735336;6088.407839374701;6089.177960975212;6099.146119543268;6107.9083813741945;6131.386861313922;5867.823965281048;6014.51883849291;6073.392660733891;6082.908756368658;6091.360407157274;6092.664903430354;6104.298005729398;6111.480524155497;6117.204763380732;6128.448439640276;5679.204911312337;5941.732622032106;5950.310807299329;5959.40405959418;5970.880582388415;5857.159277750373;5864.970675146597;5871.4715675589405;5888.293404791452;5911.970440147772;6097.04293417701;6144.477719393902;6153.13856060143;6178.443940045418;6194.194754681863;4153.483978347942;4159.33450647907;4278.379634952977;4330.41539392178;4377.0808130291725;5812.418758124154;5895.675737834289;5923.4076592340425;5933.199018132497;5945.821625351246;5788.855278618068;5789.91315130265;5801.911311927787;5813.709314534341;5817.854553636193;5523.4200408956485;5756.222909907109;5783.306003279633;5816.534677225727;5822.475977162076;5748.913766293593;5760;5769.374934404919;5773.191753154478;5776.671365586023;5687.260656114679;5839.328477225174;5844.240248767633;5872.442117998783;5900.321463031806;5676.829695109153;5683.687397193194;5699.401562835843;5701.914471282851;5726.513246373938 09:36:43 INFO - PROCESS | 4007 | |5;yui.html;766.012198345294;815.9143289954554;816.3020617372143;826.714783399727;924.7087167542228;15714.607134821621;17585;18293.451196464128;18300.72548911764;18306.26774929001;16171.757423638626;17580.912095439504;17691;17725.82274177258;17743.46769596911;818.6561644109473;845.2308399356587;849.8980122385323;850.8043150075487;852.2713080316333;702.097804321447;720.6901032555999;799.8840168175611;836.2766207230732;844.2233145506118;886.5655828643951;892.4957399069538;893.1024320557863;894.5974311559792;895.2837729816154;885.4377470306338;886.6010295367079;886.7029545102431;886.9866951995693;887.4852585500395;536.4099490560392;546.3471151973393;630.1492984470973;641.0384423364952;649.4414803269185;16689.165541722985;16713.247903844236;16741.414050508294;16742.16289185548;16756;14808.185549794756;15201.771973420407;15221.847781522154;15247.61880952974;15498.380064797395;13861.4455421783;13952.93023534886;14121.78817317741;14460.85539144616;14492.969939401159;4768.189407800683;4795.328653988445;4833.879153021203;4863.829765958175;4882.316475693406;87322;88513.11486885113;90236;90400.54799726025;90417.54791226002;12026.879731202751;12255.69360765988;12336.321502317458;12443.502259909683;12526.937365313115;22029;22030.228941986952;22050.669239961415;22067.448313791963;22084.558308834054;8475.74572762818;8730.825383492294;8748.081451447506;8748.212660860552;8936;4927.482614325516;4937.481564435754;5014.84955451337;5037.143685573461;5089.4147173074825;3445.896623101311;3580.15866271426;3583.1937813991644;3588.4976103345552;3599.7660152090552;609.6524980761012;611.7675283392372;624.4910398025595;624.5940138909655;631.4001698386551;625.6246252248615;650.5316172355896;663.6548994522822;671.664167916042;700.1143553404946;9430.292728045424;9509.33434659581;9544.904550954541;9558.665446709332;9798.951005244928;648.8248172993266;705.6330708031795;709.6806437103324;716.9928300716927;733.3802936518633;4542.704724192917;4662.7668616569035;4779.545943135397;4801.351817504592;4801.351817504662;130783;132925;134827.325863372;137244.31377843246;137774;108628;113415.43292283485;113788.43105784418;113977;115452.42273788578;79970.60014700006;82082.58958705168;97249.5137524322;100457;102110;9701.417914925127;9791.559379827782;9835.409875407498;9897.900173490203;9980.051895069957;55518.72240638771;55795;55840;57213.71393143007;57470.7126464365;74699;81515.59242203752;81981.59009204915;82171.58914205391;83720.58139709262;13796.517121900686;14015.929920350332;14164.929175354056;14173.291335433394;14330;14979.807239662136;15405.845941540441;15452.2273886303;15627.609309766893;16101.677966440371;14105.576832695035;14240;14506.782398263824;14795.852041479448;15509.76735348948;14766;15027.549173524812;15159.166245856142;15291.694166116838;15412.768808467657;14164.433422663391;14938.178400188093;15331.693366132838;15402.306896189923;15897.920510397375;12355.69110772238;12476.376181190722;12545.87854606826;12863.22820630765;13011.934940325238;12449.75100497967;12496.187747796364;12531.812022819848;12818.807717884425;12843.35783210817 09:36:43 INFO - PROCESS | 4007 | __end_tp_report 09:36:43 INFO - PROCESS | 4007 | __start_cc_report 09:36:43 INFO - PROCESS | 4007 | _x_x_mozilla_cycle_collect,896 09:36:43 INFO - PROCESS | 4007 | __end_cc_report 09:36:43 INFO - PROCESS | 4007 | __startTimestamp1476808603298__endTimestamp 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | ------- Summary: start ------- 09:36:43 INFO - PROCESS | 4007 | Number of tests: 6 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#0] dojo.html Cycles:165 Average:5541.95 Median:5708.40 stddev:640.36 (11.2%) stddev-sans-first:612.93 09:36:43 INFO - PROCESS | 4007 | Values: 3097.1 3133.7 3137.5 3151.5 3180.6 5453.0 5515.1 5529.8 5535.4 5567.2 5606.3 5670.2 5674.3 5676.9 5680.9 5065.6 5093.5 5127.3 5137.0 5167.9 4945.8 4962.9 5117.7 5171.5 5171.9 4996.2 5012.0 5023.2 5040.4 5063.4 5075.1 5090.5 5149.4 5154.2 5188.1 4875.6 4881.2 5024.5 5045.9 5121.1 5482.9 5488.3 5499.3 5508.0 5527.4 5953.8 5987.8 6045.5 6048.8 6049.2 5704.3 5796.2 6052.0 6073.7 6086.2 5554.5 5586.4 5592.2 5607.9 5650.2 6140.8 6148.4 6152.2 6155.7 6156.7 5632.8 5701.3 5912.7 5915.7 5918.6 5891.1 5936.5 5940.9 5942.7 6008.6 5937.0 5947.0 5947.1 5947.2 5951.5 5815.7 5889.7 5956.1 5959.8 5975.1 4534.3 4540.0 4559.6 4560.5 4637.0 4051.5 4068.2 4071.0 4072.0 4095.6 5680.2 5683.9 5686.6 5690.5 5708.4 5827.7 5886.8 5905.2 5939.8 5956.9 5691.3 5691.6 5697.7 5702.3 5724.4 5178.3 5187.4 5192.8 5200.0 5220.4 5879.4 5991.0 6080.3 6122.6 6173.8 6009.7 6010.8 6024.8 6034.3 6058.0 6196.0 6196.6 6200.6 6201.8 6212.2 5621.9 5635.6 5827.3 5947.4 5949.9 5984.3 6022.4 6030.8 6069.0 6071.2 5792.8 5873.9 5877.7 5898.6 5904.8 5598.2 5721.1 5875.8 5883.3 5886.7 5782.2 5802.9 5813.4 5844.8 5885.7 5915.6 5932.8 5947.3 5948.4 5954.3 5626.1 5649.3 5668.5 5820.4 5837.3 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#1] ext.html Cycles:180 Average:61322.28 Median:40224.40 stddev:120599.37 (299.8%) stddev-sans-first:120851.41 09:36:43 INFO - PROCESS | 4007 | Values: 566.1 584.3 600.3 626.3 634.8 32867.8 45045.3 45860.8 45873.0 46052.3 46950.0 47289.5 47327.5 47338.8 47406.8 8233.0 8424.8 9041.1 9197.2 9204.6 10917.3 11132.9 11144.2 11551.7 11563.1 11297.0 11399.2 11402.7 11406.8 11408.6 11127.9 11139.8 11180.6 11203.3 11204.2 10715.3 10731.3 10796.6 10803.8 10820.7 44238.3 44730.8 44741.6 44800.8 44865.0 3968.6 3977.4 3986.3 4012.3 4281.0 33976.7 34075.3 34151.3 34450.5 35062.5 4586.2 4614.2 4623.4 4702.8 4717.4 347431.0 350311.0 350583.0 351123.0 351482.0 60661.0 60937.4 60959.4 61019.4 61046.0 40237.8 40879.0 40965.8 43068.0 64064.7 18883.7 19040.7 19048.2 19048.5 19051.5 12656.7 12761.9 13801.0 14206.8 14595.2 20210.5 20250.4 20329.0 20398.2 20451.8 6989.3 7041.4 7106.3 7119.9 7518.8 907.4 947.4 961.8 969.1 982.3 30884.2 31902.0 32262.5 32265.8 32753.8 1063.0 1065.8 1070.8 1112.7 1118.3 16995.8 17344.3 17344.4 18239.9 18295.9 563646.0 567076.0 567792.0 568864.0 570364.0 50872.2 51337.5 51341.0 51473.5 51496.7 1187.7 1289.2 1346.2 1390.6 1470.0 30864.1 31443.8 32656.0 32857.5 32966.8 53029.5 53707.7 53727.7 53761.2 53811.5 417637.0 419207.0 421804.0 421804.0 423449.0 30587.2 32112.2 32120.4 33754.0 33802.3 47696.3 50347.7 50508.5 51490.5 51678.7 42858.6 43219.1 43896.0 44881.3 45303.0 43372.0 44072.0 44238.8 45628.8 46050.8 40204.4 40293.2 41188.6 41301.6 41809.4 37540.0 39162.4 39482.6 40077.0 40211.0 27794.4 27905.3 28292.0 28486.3 28570.9 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#2] jquery.html Cycles:180 Average:29510.91 Median:273588.15 stddev:85275.95 (31.2%) stddev-sans-first:85487.13 09:36:43 INFO - PROCESS | 4007 | Values: 388.4 432.5 432.7 433.1 444.1 16235.5 19255.4 21734.0 21821.3 21987.8 19891.8 20230.6 20348.8 20605.6 20828.6 5793.4 5808.7 6036.0 6042.9 6052.0 5902.1 5928.4 5937.3 5938.9 6007.6 5686.8 5843.8 5894.2 5896.2 5900.5 5883.0 5896.4 5915.1 5918.3 5930.6 5242.8 5357.7 5361.4 5446.5 5520.2 14183.5 14356.2 14381.6 14497.7 14610.5 2364.9 2422.5 2448.5 2859.5 2944.5 10368.0 10612.3 10813.9 11094.1 11208.3 1970.3 2001.8 2037.3 2042.8 2050.6 84394.6 86596.0 86600.6 86847.6 87736.7 10421.2 11375.5 11600.5 11760.3 11979.0 13907.2 15126.9 15139.2 15247.9 15771.5 8037.8 8205.7 8347.3 8394.4 8490.9 7977.1 7984.1 8005.6 8161.6 8191.0 5009.3 5068.2 5116.6 5220.7 5223.4 1932.5 2002.5 2035.6 2058.3 2081.8 843.3 888.5 942.8 949.4 975.3 17885.0 18316.7 19206.0 19512.2 20590.1 821.0 864.4 867.5 875.0 875.8 9853.0 11129.6 11402.6 11473.8 11542.3 500233.0 502336.0 508373.0 536867.0 537062.0 56924.7 89362.6 90391.5 92968.5 105617.5 502.7 523.4 524.6 525.2 527.0 10717.4 11000.7 11292.2 11431.2 11461.7 39595.4 41837.2 42406.0 43421.6 46943.3 57257.7 58548.7 61792.7 62785.7 63471.0 8865.3 8981.6 9072.9 9092.3 9165.6 12930.3 13067.4 13138.2 13388.8 13932.3 5519.8 5709.7 11432.8 11510.0 11701.6 8356.7 8704.5 11851.9 11999.9 12271.3 8233.3 8352.2 8362.4 8728.9 9117.8 11166.2 12101.5 12139.9 12845.5 12872.9 11706.2 11804.1 11846.2 11881.3 12068.0 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#3] mootools.html Cycles:180 Average:6104.74 Median:4595.44 stddev:3500.14 (76.2%) stddev-sans-first:3492.00 09:36:43 INFO - PROCESS | 4007 | Values: 1386.8 1448.2 1460.4 1498.5 1533.4 8026.6 8959.8 9315.0 9325.5 9608.7 9387.4 9444.5 9464.5 9490.3 9501.1 2491.6 2518.4 2519.2 2520.2 2523.1 2438.3 2481.7 2482.2 2485.6 2488.2 2433.1 2446.2 2486.0 2487.9 2490.0 2372.0 2380.8 2400.4 2431.9 2432.6 2334.4 2375.0 2375.2 2376.4 2420.0 9160.1 9268.8 9278.7 9332.5 9336.7 5490.4 5701.7 5707.3 5709.6 5714.4 3607.9 3639.1 3649.9 3672.9 3771.8 1212.0 1216.4 1224.9 1232.8 1262.4 12688.7 12787.9 12805.4 12852.3 12896.2 4509.7 4678.2 4797.7 4812.7 4819.1 10205.8 10210.1 10210.8 10214.2 10217.2 5457.6 5472.4 5482.7 5500.0 5507.0 4262.6 4536.0 4654.9 4662.3 5141.5 4064.7 4160.5 4164.5 4239.5 4258.2 1239.7 1258.7 1264.0 1269.2 1273.7 807.3 849.6 882.3 905.2 927.2 6283.3 6340.3 6387.7 6437.2 6599.1 945.3 966.8 1000.6 1025.7 1036.5 4352.1 4359.7 4386.5 4418.4 4430.7 11477.5 11713.9 11848.7 11849.6 11852.4 11345.3 11705.1 11710.7 11714.6 11811.4 8679.0 8723.5 8820.4 8834.2 8841.0 6160.7 6234.6 6503.3 6511.4 6569.0 10718.4 10805.6 11030.4 11086.8 11091.2 10883.5 11086.7 11123.1 11146.9 11178.8 7641.3 7691.3 7758.0 7759.7 7761.8 9091.1 9187.0 9202.5 9203.2 9225.3 8117.7 8148.1 8212.5 8218.1 8391.6 7500.0 7617.5 7629.4 7681.8 7834.4 7376.7 7478.8 7485.4 7488.6 7495.3 5218.3 5218.4 5219.0 5301.3 5483.2 5532.8 5533.1 5604.9 5647.5 5691.8 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#4] prototype.html Cycles:180 Average:4805.79 Median:5325.14 stddev:1418.15 (26.6%) stddev-sans-first:1381.92 09:36:43 INFO - PROCESS | 4007 | Values: 338.6 345.9 351.7 353.8 355.5 5500.6 5530.7 5548.3 5564.8 5598.3 5167.4 5310.4 5312.2 5416.2 5450.2 4286.4 4302.2 4305.5 4308.6 4310.9 4301.5 4306.2 4314.1 4321.4 4328.5 4092.5 4227.4 4245.8 4247.5 4249.6 4230.5 4239.7 4241.4 4244.3 4247.6 3738.8 3744.3 3745.3 3747.4 3747.9 5210.9 5254.4 5322.8 5327.4 5392.8 4854.7 4857.7 4860.7 4868.7 4883.6 5703.5 5708.6 5723.8 5727.4 5751.9 3934.5 4149.8 4151.8 4160.5 4165.8 5958.5 5982.9 5985.3 5986.7 6000.9 4224.8 4227.4 4237.4 4264.9 4265.8 4119.9 4288.6 4305.4 4308.2 4317.1 4850.9 4870.6 4878.1 4889.6 4898.3 5743.5 5787.9 5795.2 5845.5 5883.1 1413.2 1454.1 1471.6 1486.6 1489.1 913.4 919.8 933.9 936.2 945.8 4867.8 4868.8 4878.8 4901.0 4944.7 5134.4 5167.1 5178.0 5201.3 5219.8 4917.9 4941.8 4959.2 4981.9 5231.8 3107.4 3137.6 3143.9 3149.0 3151.8 6088.4 6089.2 6099.1 6107.9 6131.4 5867.8 6014.5 6073.4 6082.9 6091.4 6092.7 6104.3 6111.5 6117.2 6128.4 5679.2 5941.7 5950.3 5959.4 5970.9 5857.2 5865.0 5871.5 5888.3 5912.0 6097.0 6144.5 6153.1 6178.4 6194.2 4153.5 4159.3 4278.4 4330.4 4377.1 5812.4 5895.7 5923.4 5933.2 5945.8 5788.9 5789.9 5801.9 5813.7 5817.9 5523.4 5756.2 5783.3 5816.5 5822.5 5748.9 5760.0 5769.4 5773.2 5776.7 5687.3 5839.3 5844.2 5872.4 5900.3 5676.8 5683.7 5699.4 5701.9 5726.5 09:36:43 INFO - PROCESS | 4007 | 09:36:43 INFO - PROCESS | 4007 | [#5] yui.html Cycles:180 Average:23196.02 Median:4787.44 stddev:34109.22 (712.5%) stddev-sans-first:34163.33 09:36:43 INFO - PROCESS | 4007 | Values: 766.0 815.9 816.3 826.7 924.7 15714.6 17585.0 18293.5 18300.7 18306.3 16171.8 17580.9 17691.0 17725.8 17743.5 818.7 845.2 849.9 850.8 852.3 702.1 720.7 799.9 836.3 844.2 886.6 892.5 893.1 894.6 895.3 885.4 886.6 886.7 887.0 887.5 536.4 546.3 630.1 641.0 649.4 16689.2 16713.2 16741.4 16742.2 16756.0 14808.2 15201.8 15221.8 15247.6 15498.4 13861.4 13952.9 14121.8 14460.9 14493.0 4768.2 4795.3 4833.9 4863.8 4882.3 87322.0 88513.1 90236.0 90400.5 90417.5 12026.9 12255.7 12336.3 12443.5 12526.9 22029.0 22030.2 22050.7 22067.4 22084.6 8475.7 8730.8 8748.1 8748.2 8936.0 4927.5 4937.5 5014.8 5037.1 5089.4 3445.9 3580.2 3583.2 3588.5 3599.8 609.7 611.8 624.5 624.6 631.4 625.6 650.5 663.7 671.7 700.1 9430.3 9509.3 9544.9 9558.7 9799.0 648.8 705.6 709.7 717.0 733.4 4542.7 4662.8 4779.5 4801.4 4801.4 130783.0 132925.0 134827.3 137244.3 137774.0 108628.0 113415.4 113788.4 113977.0 115452.4 79970.6 82082.6 97249.5 100457.0 102110.0 9701.4 9791.6 9835.4 9897.9 9980.1 55518.7 55795.0 55840.0 57213.7 57470.7 74699.0 81515.6 81981.6 82171.6 83720.6 13796.5 14015.9 14164.9 14173.3 14330.0 14979.8 15405.8 15452.2 15627.6 16101.7 14105.6 14240.0 14506.8 14795.9 15509.8 14766.0 15027.5 15159.2 15291.7 15412.8 14164.4 14938.2 15331.7 15402.3 15897.9 12355.7 12476.4 12545.9 12863.2 13011.9 12449.8 12496.2 12531.8 12818.8 12843.4 09:36:43 INFO - PROCESS | 4007 | -------- Summary: end -------- 09:36:43 INFO - PROCESS | 4007 | 09:36:44 INFO - TEST-INFO | 4007: exit 0 09:36:44 INFO - TEST-OK | dromaeo_css | took 1114086ms 09:36:44 INFO - TEST-START | kraken 09:36:44 INFO - operating with platform_type : linux_ 09:36:44 INFO - Initialising browser for kraken test... 09:36:44 INFO - TEST-INFO | started process 4322 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgufEM2/profile http://localhost:48631/getInfo.html) 09:36:48 INFO - PROCESS | 4322 | __metrics Screen width/height:1600/1200 09:36:48 INFO - PROCESS | 4322 | colorDepth:24 09:36:48 INFO - PROCESS | 4322 | Browser inner width/height: 1024/697 09:36:48 INFO - PROCESS | 4322 | __metrics 09:36:49 INFO - PROCESS | 4322 | 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:36:49 INFO - PROCESS | 4322 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:49 INFO - PROCESS | 4322 | console.error: PushService: 09:36:49 INFO - PROCESS | 4322 | stateChangeProcessEnqueue: Error transitioning state 09:36:49 INFO - PROCESS | 4322 | UnknownError 09:36:49 INFO - TEST-INFO | 4322: exit 0 09:36:49 INFO - Browser initialized. 09:36:49 INFO - Running cycle 1/1 for kraken test... 09:36:49 INFO - TEST-INFO | started process 4412 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgufEM2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:01 INFO - PROCESS | 4412 | RSS: Main: 144445440 09:37:01 INFO - PROCESS | 4412 | 09:37:04 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:05 INFO - PROCESS | 4412 | RSS: Main: 186372096 09:37:05 INFO - PROCESS | 4412 | 09:37:07 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-dft) 09:37:08 INFO - PROCESS | 4412 | RSS: Main: 176168960 09:37:08 INFO - PROCESS | 4412 | 09:37:11 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-fft) 09:37:11 INFO - PROCESS | 4412 | RSS: Main: 267354112 09:37:11 INFO - PROCESS | 4412 | 09:37:13 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48631/tests/kraken/driver.html?testName=audio-oscillator) 09:37:14 INFO - PROCESS | 4412 | RSS: Main: 173596672 09:37:14 INFO - PROCESS | 4412 | 09:37:16 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:17 INFO - PROCESS | 4412 | RSS: Main: 175124480 09:37:17 INFO - PROCESS | 4412 | 09:37:20 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:20 INFO - PROCESS | 4412 | RSS: Main: 211472384 09:37:20 INFO - PROCESS | 4412 | 09:37:24 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48631/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:24 INFO - PROCESS | 4412 | RSS: Main: 210100224 09:37:24 INFO - PROCESS | 4412 | 09:37:27 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48631/tests/kraken/driver.html?testName=json-parse-financial) 09:37:27 INFO - PROCESS | 4412 | RSS: Main: 210116608 09:37:27 INFO - PROCESS | 4412 | 09:37:29 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48631/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:37:30 INFO - PROCESS | 4412 | RSS: Main: 171401216 09:37:30 INFO - PROCESS | 4412 | 09:37:31 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:37:32 INFO - PROCESS | 4412 | RSS: Main: 178937856 09:37:32 INFO - PROCESS | 4412 | 09:37:34 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:37:34 INFO - PROCESS | 4412 | RSS: Main: 188407808 09:37:34 INFO - PROCESS | 4412 | 09:37:37 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:37:38 INFO - PROCESS | 4412 | RSS: Main: 197677056 09:37:38 INFO - PROCESS | 4412 | 09:37:40 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:37:41 INFO - PROCESS | 4412 | RSS: Main: 193470464 09:37:41 INFO - PROCESS | 4412 | 09:37:42 INFO - PROCESS | 4412 | Cycle 1(1): loaded http://localhost:48631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48631/tests/kraken/driver.html?testName=ai-astar) 09:37:43 INFO - PROCESS | 4412 | RSS: Main: 245026816 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | __start_tp_report 09:37:43 INFO - PROCESS | 4412 | _x_x_mozilla_page_load 09:37:43 INFO - PROCESS | 4412 | _x_x_mozilla_page_load_details 09:37:43 INFO - PROCESS | 4412 | |i|pagename|runs| 09:37:43 INFO - PROCESS | 4412 | |0;ai-astar;110;113;111;111;111;113;113;110;111;111 09:37:43 INFO - PROCESS | 4412 | |1;audio-beat-detection;148;118;118;154;122;118;119;118;145;119 09:37:43 INFO - PROCESS | 4412 | |2;audio-dft;160;160;160;160;163;161;160;161;161;162 09:37:43 INFO - PROCESS | 4412 | |3;audio-fft;75;76;76;104;77;76;77;75;97;76 09:37:43 INFO - PROCESS | 4412 | |4;audio-oscillator;82;82;109;82;81;82;100;81;81;107 09:37:43 INFO - PROCESS | 4412 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:37:43 INFO - PROCESS | 4412 | |6;imaging-darkroom;145;146;146;147;146;146;146;148;147;145 09:37:43 INFO - PROCESS | 4412 | |7;imaging-desaturate;95;95;95;95;96;96;95;95;95;95 09:37:43 INFO - PROCESS | 4412 | |8;json-parse-financial;55;50;52;50;49;50;50;49;50;49 09:37:43 INFO - PROCESS | 4412 | |9;json-stringify-tinderbox;57;59;58;58;59;58;58;59;59;58 09:37:43 INFO - PROCESS | 4412 | |10;stanford-crypto-aes;74;73;73;75;74;75;73;77;81;76 09:37:43 INFO - PROCESS | 4412 | |11;stanford-crypto-ccm;128;122;123;121;119;121;122;122;150;151 09:37:43 INFO - PROCESS | 4412 | |12;stanford-crypto-pbkdf2;152;153;152;155;152;156;152;152;151;152 09:37:43 INFO - PROCESS | 4412 | |13;stanford-crypto-sha256-iterative;63;63;63;62;62;63;62;61;62;64 09:37:43 INFO - PROCESS | 4412 | __end_tp_report 09:37:43 INFO - PROCESS | 4412 | __start_cc_report 09:37:43 INFO - PROCESS | 4412 | _x_x_mozilla_cycle_collect,1591 09:37:43 INFO - PROCESS | 4412 | __end_cc_report 09:37:43 INFO - PROCESS | 4412 | __startTimestamp1476808663244__endTimestamp 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | ------- Summary: start ------- 09:37:43 INFO - PROCESS | 4412 | Number of tests: 14 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#0] ai-astar Cycles:10 Average:111.40 Median:111.00 stddev:1.17 (1.1%) stddev-sans-first:1.13 09:37:43 INFO - PROCESS | 4412 | Values: 110.0 113.0 111.0 111.0 111.0 113.0 113.0 110.0 111.0 111.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:120.50 stddev:14.77 (12.3%) stddev-sans-first:13.76 09:37:43 INFO - PROCESS | 4412 | Values: 148.0 118.0 118.0 154.0 122.0 118.0 119.0 118.0 145.0 119.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.05 09:37:43 INFO - PROCESS | 4412 | Values: 160.0 160.0 160.0 160.0 163.0 161.0 160.0 161.0 161.0 162.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:10.48 (13.8%) stddev-sans-first:10.90 09:37:43 INFO - PROCESS | 4412 | Values: 75.0 76.0 76.0 104.0 77.0 76.0 77.0 75.0 97.0 76.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:81.50 stddev:11.70 (14.4%) stddev-sans-first:12.16 09:37:43 INFO - PROCESS | 4412 | Values: 82.0 82.0 109.0 82.0 81.0 82.0 100.0 81.0 81.0 107.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:37:43 INFO - PROCESS | 4412 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 09:37:43 INFO - PROCESS | 4412 | Values: 145.0 146.0 146.0 147.0 146.0 146.0 146.0 148.0 147.0 145.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:37:43 INFO - PROCESS | 4412 | Values: 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.00 stddev:1.84 (3.7%) stddev-sans-first:0.93 09:37:43 INFO - PROCESS | 4412 | Values: 55.0 50.0 52.0 50.0 49.0 50.0 50.0 49.0 50.0 49.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.50 stddev:0.67 (1.2%) stddev-sans-first:0.53 09:37:43 INFO - PROCESS | 4412 | Values: 57.0 59.0 58.0 58.0 59.0 58.0 58.0 59.0 59.0 58.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:2.47 (3.3%) stddev-sans-first:2.59 09:37:43 INFO - PROCESS | 4412 | Values: 74.0 73.0 73.0 75.0 74.0 75.0 73.0 77.0 81.0 76.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:122.50 stddev:12.13 (9.9%) stddev-sans-first:12.87 09:37:43 INFO - PROCESS | 4412 | Values: 128.0 122.0 123.0 121.0 119.0 121.0 122.0 122.0 150.0 151.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:152.00 stddev:1.57 (1.0%) stddev-sans-first:1.64 09:37:43 INFO - PROCESS | 4412 | Values: 152.0 153.0 152.0 155.0 152.0 156.0 152.0 152.0 151.0 152.0 09:37:43 INFO - PROCESS | 4412 | 09:37:43 INFO - PROCESS | 4412 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:63.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 09:37:43 INFO - PROCESS | 4412 | Values: 63.0 63.0 63.0 62.0 62.0 63.0 62.0 61.0 62.0 64.0 09:37:43 INFO - PROCESS | 4412 | -------- Summary: end -------- 09:37:43 INFO - PROCESS | 4412 | 09:37:44 INFO - TEST-INFO | 4412: exit 0 09:37:44 INFO - TEST-OK | kraken | took 60126ms 09:37:44 INFO - SUITE-END | took 1174s 09:37:44 INFO - Completed test suite (00:19:34) 09:37:44 INFO - javascript benchmark 09:37:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3097.1173215633544, 3133.733632641226, 3137.513685378766, 3151.5430262611935, 3180.634227063887, 5452.9727351363235, 5515.117581187006, 5529.778808847641, 5535.446455354472, 5567.220589117522, 5606.327240731118, 5670.177824215486, 5674.262345895038, 5676.858078548038, 5680.857978550538, 5065.569426598734, 5093.516115968977, 5127.307813445174, 5137.0, 5167.948320516784, 4945.7774400152075, 4962.851114466572, 5117.744112794364, 5171.482851714836, 5171.87070323243, 4996.1756310208775, 5011.974940125294, 5023.2214006829, 5040.420351659551, 5063.392392912856, 5075.06111369396, 5090.516400941923, 5149.356330458692, 5154.201098829687, 5188.092083885343, 4875.58557522611, 4881.194602890519, 5024.497550244982, 5045.924311135336, 5121.052605268, 5482.91775623366, 5488.25908502355, 5499.312585926759, 5507.972460137674, 5527.364353099365, 5953.791617293417, 5987.760489580455, 6045.546584006217, 6048.8185354439, 6049.213602231725, 5704.258446401983, 5796.217510636052, 6052.0, 6073.696315184223, 6086.1783659205885, 5554.50009499143, 5586.413426590231, 5592.161175823659, 5607.887842243133, 5650.237217975603, 6140.754369825247, 6148.415900489446, 6152.169457123231, 6155.692215389213, 6156.661383623944, 5632.802851900164, 5701.344345400249, 5912.733926973297, 5915.70421478933, 5918.556108291896, 5891.086881533369, 5936.5250779937505, 5940.910886336708, 5942.732577034044, 6008.579399442085, 5937.0, 5947.04847224442, 5947.1376650385455, 5947.226860508193, 5951.4941229995065, 5815.73829177688, 5889.705514724246, 5956.076808094752, 5959.761609535657, 5975.103734439869, 4534.251848445036, 4540.0, 4559.566841150086, 4560.498345182027, 4637.049404871993, 4051.47330846988, 4068.2270368630457, 4071.0229544909007, 4072.0, 4095.6313931746286, 5680.233168522279, 5683.914741278884, 5686.630369026, 5690.516306113944, 5708.400617935057, 5827.679477628686, 5886.793962211302, 5905.202797622352, 5939.792107276225, 5956.940430595725, 5691.288588926384, 5691.630044047207, 5697.715114244271, 5702.344230413472, 5724.398938111522, 5178.275041494194, 5187.429382767892, 5192.766325515361, 5200.0, 5220.3735551734035, 5879.353271140171, 5990.9700451497465, 6080.270367555921, 6122.5811271886105, 6173.84565385869, 6009.699515024318, 6010.759569617167, 6024.789132380346, 6034.336223015553, 6057.969710151421, 6195.96902015487, 6196.566240363311, 6200.56596038291, 6201.751929922752, 6212.161358216583, 5621.915671264853, 5635.633683810539, 5827.271591051119, 5947.405259474018, 5949.940500594938, 5984.341722410444, 6022.427869352405, 6030.75876964934, 6069.02895536712, 6071.210742603434, 5792.82621521355, 5873.9412605873395, 5877.735501902339, 5898.64608123514, 5904.763809447574, 5598.160275958476, 5721.084626459747, 5875.764969401176, 5883.323417807007, 5886.705664716833, 5782.161586570008, 5802.941970580409, 5813.418658134153, 5844.824655260349, 5885.705714714333, 5915.615484993462, 5932.762689492372, 5947.3457919627945, 5948.40515948419, 5954.255718035246, 5626.099824028136, 5649.293838270216, 5668.5465162787705, 5820.447057529528, 5837.29952405714], "value": 5497.476612535176, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.1065583736594, 584.330941072472, 600.2976517474556, 626.2922897126247, 634.752446545847, 32867.83566082169, 45045.32432013522, 45860.77069614656, 45873.0, 46052.309215361754, 46950.0, 47289.52710472903, 47327.52672473274, 47338.76330618342, 47406.762966185204, 8233.0, 8424.789380265482, 9041.050689677571, 9197.172254497094, 9204.631814727403, 10917.344959302467, 11132.888671113307, 11144.164187685916, 11551.65345039646, 11563.132765042614, 11297.039751621121, 11399.202055856093, 11402.71493212668, 11406.828897566544, 11408.600698975537, 11127.944360278147, 11139.832902506469, 11180.608678696288, 11203.327800331925, 11204.21570490062, 10715.303505272132, 10731.302465339806, 10796.622118225903, 10803.83794243087, 10820.67537973862, 44238.33642495365, 44730.776346118386, 44741.55258447406, 44800.77599612014, 44865.0, 3968.5634580196156, 3977.363621820524, 3986.2824691555506, 4012.3379642358977, 4281.036766727492, 33976.6602333976, 34075.318493630235, 34151.31697366039, 34450.483242751376, 35062.47406288908, 4586.151561961048, 4614.169449499089, 4623.398958135454, 4702.788374523155, 4717.386739723848, 347431.0, 350311.0, 350583.0, 351123.0, 351481.99999999744, 60661.0, 60937.39062609406, 60959.39040609626, 61019.38980610137, 61046.0, 40237.798811005756, 40879.0, 40965.79517102396, 43068.0, 64064.67967660132, 18883.716744248846, 19040.71438928417, 19048.238070477026, 19048.52378690544, 19051.523711907314, 12656.74686506283, 12761.872381276255, 13801.0, 14206.786898196535, 14595.197264150574, 20210.494737631678, 20250.392488225378, 20328.9835508224, 20398.184072637225, 20451.772893626134, 6989.30106989297, 7041.40148087408, 7106.289371062956, 7119.935218786494, 7518.849623007509, 907.4056492997097, 947.4220725357526, 961.778790878102, 969.1229437359201, 982.258394911837, 30884.227894302374, 31902.0, 32262.516062259085, 32265.838670806497, 32753.83623081917, 1062.9848494687556, 1065.760203954104, 1070.839374093892, 1112.7218195451137, 1118.3066498770813, 16995.83004169967, 17344.30622775075, 17344.392946246822, 18239.908800455913, 18295.908520457313, 563646.0, 567076.0, 567792.0, 568864.0, 570364.0, 50872.23691644628, 51337.48662513402, 51341.0, 51473.48526514762, 51496.74251628718, 1187.7386974865515, 1289.2457912121326, 1346.172104155937, 1390.6245313765228, 1469.963250918757, 30864.074077777703, 31443.842780785948, 32656.0, 32857.50713739343, 32966.83516582402, 53029.46970530245, 53707.731461342446, 53727.73136134294, 53761.19358209552, 53811.46188538221, 417637.0, 419207.0, 421804.0, 421804.0, 423449.0, 30587.2353191172, 32112.197195070312, 32120.35759284848, 33754.0, 33802.3239535203, 47696.28455573239, 50347.74826125846, 50508.49491505185, 51490.48509514857, 51678.74160629323, 42858.57141428671, 43219.119060783014, 43896.0, 44881.32678009767, 45303.0, 43372.0, 44072.0, 44238.77880610705, 45628.771856140505, 46050.769746151054, 40204.39693404543, 40293.1941361177, 41188.58811411967, 41301.586984129775, 41809.37285940774, 37540.0, 39162.412563811, 39482.60517394789, 40077.0, 40211.0, 27794.44411111726, 27905.302367440978, 28292.0, 28486.287842804097, 28570.857145714137], "value": 21044.935658658153, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.4309486602127, 432.5134223998002, 432.6949500602076, 433.0862270776433, 444.1317224825469, 16235.512934611967, 19255.422337329885, 21734.0, 21821.345359639196, 21987.780122198772, 19891.801081989175, 20230.595388092264, 20348.796512034874, 20605.587888242226, 20828.58342833146, 5793.4206579341935, 5808.680522571257, 6035.969820150893, 6042.939570604303, 6052.0, 5902.055671092626, 5928.377520360344, 5937.346891841894, 5938.940610593903, 6007.609505382158, 5686.800961966332, 5843.824685259449, 5894.17481552583, 5896.204012458327, 5900.498457631085, 5883.0, 5896.351401345849, 5915.112733090028, 5918.260217472816, 5930.555208359368, 5242.79028838846, 5357.732113394353, 5361.410244873061, 5446.455354464561, 5520.227168196456, 14183.503577374742, 14356.210408427532, 14381.64045898861, 14497.710045799131, 14610.488632897797, 2364.869932153731, 2422.4912768318673, 2448.4735781806817, 2859.4852926473227, 2944.528875379929, 10368.0, 10612.289700041916, 10813.891861081367, 11094.057005154571, 11208.327500349924, 1970.3103913630114, 2001.7798042215345, 2037.2971324892887, 2042.8263597594223, 2050.5693804301113, 84394.57802710947, 86596.0, 86600.56699716524, 86847.56576217142, 87736.68394974145, 10421.186279704438, 11375.544978200945, 11600.535978560763, 11760.294382337088, 11979.0, 13907.235102069484, 15126.924365378323, 15139.167345795866, 15247.923760381349, 15771.526854194393, 8037.839243215103, 8205.671773129128, 8347.332213422911, 8394.370422218359, 8490.915090849136, 7977.122516523178, 7984.121746607869, 8005.639746211435, 8161.632726527202, 8191.017077950565, 5009.273655320031, 5068.214426763784, 5116.641835071509, 5220.712860792617, 5223.373195216598, 1932.468571142947, 2002.549426379053, 2035.5928814236916, 2058.310465993879, 2081.7501899771823, 843.3421930893912, 888.4669198480859, 942.7595963029383, 949.3734135470694, 975.2539307429822, 17885.0, 18316.725249121275, 19206.0, 19512.219511219675, 20590.073446694936, 820.9958950205211, 864.4424346296604, 867.5011868175824, 875.0330884372804, 875.7831043525405, 9852.95073524642, 11129.610463633733, 11402.600908968147, 11473.827892581618, 11542.307461552333, 500233.0, 502336.0, 508373.0, 536867.0, 537062.0, 56924.71537642285, 89362.55318723364, 90391.54804225937, 92968.53515732379, 105617.47191264149, 502.68833323339766, 523.3667262612206, 524.5803357314134, 525.1518797142587, 527.0302643136723, 10717.410542420243, 11000.669979900616, 11292.209545332076, 11431.199816012799, 11461.713457163638, 39595.406068909564, 41837.1632567353, 42406.0, 43421.56578434175, 46943.29585056158, 57257.71371143118, 58548.70725646344, 61792.69103654453, 62785.68607156935, 63471.0, 8865.335099867407, 8981.640734370814, 9072.863907041532, 9092.318076144184, 9165.633374664938, 12930.288834114213, 13067.411966461343, 13138.211707297593, 13388.799168012682, 13932.30338483092, 5519.779208831602, 5709.7145142741865, 11432.771344573228, 11510.04329264044, 11701.590444334408, 8356.665733370597, 8704.477731336141, 11851.940740296588, 11999.940000299943, 12271.263724176579, 8233.300169485661, 8352.206540378655, 8362.372822038493, 8728.869066964127, 9117.77205569866, 11166.218364714392, 12101.455434505644, 12139.939300303795, 12845.486180552673, 12872.871271287542, 11706.180567360203, 11804.114691398352, 11846.170768046153, 11881.346525941157, 12068.0], "value": 8919.94204081087, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.7503849307134, 1448.232436808492, 1460.430432131469, 1498.4980031689388, 1533.4173014254584, 8026.598670066495, 8959.776005599862, 9315.0, 9325.487098209604, 9608.663696770614, 9387.389819661734, 9444.48055356955, 9464.479453630047, 9490.335676502647, 9501.14489695929, 2491.563976304144, 2518.357818756215, 2519.1938579654516, 2520.155747824481, 2523.079076137206, 2438.329459398662, 2481.727010028895, 2482.1932871816643, 2485.6023036314195, 2488.191337815208, 2433.051110067075, 2446.229437727119, 2486.0, 2487.9129230476938, 2490.0413340863806, 2371.9525609487887, 2380.821438392119, 2400.3999000249937, 2431.8540887546806, 2432.6107822748277, 2334.428583537337, 2375.0499800079933, 2375.2161786610377, 2376.3940195250275, 2420.0198919437657, 9160.083991600854, 9268.768280792967, 9278.721638350793, 9332.48671323077, 9336.719898403058, 5490.396056433789, 5701.6864072476, 5707.258056452634, 5709.600327977043, 5714.371419143891, 3607.9458808117897, 3639.1448009717697, 3649.908752281188, 3672.889813305605, 3771.830267637963, 1211.987880121205, 1216.4343580235145, 1224.8836360545733, 1232.796588562886, 1262.4192871279222, 12688.74622507554, 12787.872121278855, 12805.359732013361, 12852.293123878182, 12896.161749486348, 4509.6843220974215, 4678.157931572282, 4797.664163508521, 4812.711237325771, 4819.084373969004, 10205.846912296322, 10210.132138768287, 10210.846837297446, 10214.182865370753, 10217.233707471969, 5457.617966742369, 5472.398036216013, 5482.671039737628, 5500.0, 5506.972465137648, 4262.6376757975295, 4535.954640453553, 4654.930176047362, 4662.300654901724, 5141.511556402136, 4064.695147863922, 4160.502219201086, 4164.501299220504, 4239.491261048694, 4258.233517966796, 1239.6838806104386, 1258.6979125009932, 1263.9810402843964, 1269.1940617707821, 1273.6943133647858, 807.3460496997451, 849.609179777297, 882.2544949517647, 905.1536813079791, 927.1933417926313, 6283.340249273851, 6340.302566717657, 6387.680615969183, 6437.227532696106, 6599.1091202687985, 945.2957546627806, 966.7583104223944, 1000.6447711062468, 1025.733309339577, 1036.512838965685, 4352.129574085196, 4359.738415695068, 4386.539413361551, 4418.403515525427, 4430.734155950653, 11477.483513241925, 11713.88286117128, 11848.70378240551, 11849.644510664693, 11852.407379630984, 11345.262557933707, 11705.122115841346, 11710.70723231909, 11714.648560543197, 11811.350375729417, 8679.0, 8723.47659140441, 8820.382573219813, 8834.249088827522, 8841.027486976427, 6160.691965401622, 6234.628381756006, 6503.284638689645, 6511.38141876521, 6569.0, 10718.35689858611, 10805.621803236849, 11030.393328367018, 11086.778264434504, 11091.223614346918, 10883.455827208767, 11086.722831929266, 11123.110151187724, 11146.944265278622, 11178.832317515407, 7641.312281894656, 7691.269329413697, 7758.0, 7759.650815713419, 7761.844763104819, 9091.090890910857, 9186.954065229631, 9202.493862837608, 9203.171714545722, 9225.261979041527, 8117.715879944174, 8148.0598438338575, 8212.46618969765, 8218.137095605114, 8391.580420978804, 7499.962500187464, 7617.54294742295, 7629.351505122127, 7681.807954801175, 7834.373250139861, 7376.704931802882, 7478.775636730907, 7485.401167906663, 7488.625568721651, 7495.287947644965, 5218.347706536683, 5218.373795144607, 5219.0, 5301.3373328333955, 5483.150111732688, 5532.806351777829, 5533.114701647717, 5604.85987850307, 5647.463490968352, 5691.772329106955], "value": 4850.767987300522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.62581847059, 345.88585766696986, 351.68172803612737, 353.78065599328414, 355.51117213831, 5500.614956953015, 5530.723463826807, 5548.250986116873, 5564.8330550083565, 5598.300212473441, 5167.379914410276, 5310.36275646922, 5312.203169524573, 5416.214648875911, 5450.236966824647, 4286.39990401344, 4302.182585308797, 4305.461817272833, 4308.633766129883, 4310.892227694301, 4301.483821941371, 4306.181825453154, 4314.115606300701, 4321.438213032301, 4328.458942632171, 4092.4653255029366, 4227.4292970448905, 4245.787710614472, 4247.532771395144, 4249.575042495741, 4230.471191101113, 4239.7032207745315, 4241.427407300006, 4244.278472659656, 4247.638950689196, 3738.813059347022, 3744.307303148899, 3745.3445647011663, 3747.362948298796, 3747.868824591126, 5210.869728256786, 5254.395744489394, 5322.840314790563, 5327.440618735016, 5392.838214853561, 4854.660173787836, 4857.68425052374, 4860.708357498526, 4868.659193856432, 4883.584895283905, 5703.545595873067, 5708.628939118944, 5723.828285151452, 5727.3986231445515, 5751.884962300773, 3934.4885164928664, 4149.792510374469, 4151.841042657801, 4160.5007399112, 4165.83336666533, 5958.493528050121, 5982.850428739316, 5985.311689155803, 5986.73059712314, 6000.879982400371, 4224.809883555247, 4227.408162857202, 4237.449131612932, 4264.893377665583, 4265.786710664454, 4119.938200926988, 4288.571142885687, 4305.440292761921, 4308.246056940023, 4317.11499142675, 4850.854474365775, 4870.636221857814, 4878.097551952829, 4889.584385327287, 4898.338724272249, 5743.4543718346695, 5787.913181302284, 5795.217645617872, 5845.509395104221, 5883.146943693226, 1413.2439145057344, 1454.1275234859, 1471.5805995291291, 1486.6134804950575, 1489.143742348154, 913.4336711239074, 919.7516670499061, 933.874681009382, 936.2089034765612, 945.8486642137362, 4867.829625963074, 4868.758466590996, 4878.804847806119, 4901.0, 4944.714374262646, 5134.383873935151, 5167.114003388811, 5178.016176926447, 5201.349831271091, 5219.8434046978655, 4917.852464426073, 4941.827036053793, 4959.231319145538, 4981.92888528968, 5231.8168864089575, 3107.425126351632, 3137.5921130252923, 3143.905682829519, 3149.0, 3151.7865621735336, 6088.407839374701, 6089.177960975212, 6099.146119543268, 6107.9083813741945, 6131.386861313922, 5867.823965281048, 6014.51883849291, 6073.392660733891, 6082.908756368658, 6091.360407157274, 6092.664903430354, 6104.298005729398, 6111.480524155497, 6117.204763380732, 6128.448439640276, 5679.204911312337, 5941.732622032106, 5950.310807299329, 5959.40405959418, 5970.880582388415, 5857.159277750373, 5864.970675146597, 5871.4715675589405, 5888.293404791452, 5911.970440147772, 6097.04293417701, 6144.477719393902, 6153.13856060143, 6178.443940045418, 6194.194754681863, 4153.483978347942, 4159.33450647907, 4278.379634952977, 4330.41539392178, 4377.0808130291725, 5812.418758124154, 5895.675737834289, 5923.4076592340425, 5933.199018132497, 5945.821625351246, 5788.855278618068, 5789.91315130265, 5801.911311927787, 5813.709314534341, 5817.854553636193, 5523.4200408956485, 5756.222909907109, 5783.306003279633, 5816.534677225727, 5822.475977162076, 5748.913766293593, 5760.0, 5769.374934404919, 5773.191753154478, 5776.671365586023, 5687.260656114679, 5839.328477225174, 5844.240248767633, 5872.442117998783, 5900.321463031806, 5676.829695109153, 5683.687397193194, 5699.401562835843, 5701.914471282851, 5726.513246373938], "value": 4353.883137111861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.012198345294, 815.9143289954554, 816.3020617372143, 826.714783399727, 924.7087167542228, 15714.607134821621, 17585.0, 18293.451196464128, 18300.72548911764, 18306.26774929001, 16171.757423638626, 17580.912095439504, 17691.0, 17725.82274177258, 17743.46769596911, 818.6561644109473, 845.2308399356587, 849.8980122385323, 850.8043150075487, 852.2713080316333, 702.097804321447, 720.6901032555999, 799.8840168175611, 836.2766207230732, 844.2233145506118, 886.5655828643951, 892.4957399069538, 893.1024320557863, 894.5974311559792, 895.2837729816154, 885.4377470306338, 886.6010295367079, 886.7029545102431, 886.9866951995693, 887.4852585500395, 536.4099490560392, 546.3471151973393, 630.1492984470973, 641.0384423364952, 649.4414803269185, 16689.165541722985, 16713.247903844236, 16741.414050508294, 16742.16289185548, 16756.0, 14808.185549794756, 15201.771973420407, 15221.847781522154, 15247.61880952974, 15498.380064797395, 13861.4455421783, 13952.93023534886, 14121.78817317741, 14460.85539144616, 14492.969939401159, 4768.189407800683, 4795.328653988445, 4833.879153021203, 4863.829765958175, 4882.316475693406, 87322.0, 88513.11486885113, 90236.0, 90400.54799726025, 90417.54791226002, 12026.879731202751, 12255.69360765988, 12336.321502317458, 12443.502259909683, 12526.937365313115, 22029.0, 22030.228941986952, 22050.669239961415, 22067.448313791963, 22084.558308834054, 8475.74572762818, 8730.825383492294, 8748.081451447506, 8748.212660860552, 8936.0, 4927.482614325516, 4937.481564435754, 5014.84955451337, 5037.143685573461, 5089.4147173074825, 3445.896623101311, 3580.15866271426, 3583.1937813991644, 3588.4976103345552, 3599.7660152090552, 609.6524980761012, 611.7675283392372, 624.4910398025595, 624.5940138909655, 631.4001698386551, 625.6246252248615, 650.5316172355896, 663.6548994522822, 671.664167916042, 700.1143553404946, 9430.292728045424, 9509.33434659581, 9544.904550954541, 9558.665446709332, 9798.951005244928, 648.8248172993266, 705.6330708031795, 709.6806437103324, 716.9928300716927, 733.3802936518633, 4542.704724192917, 4662.7668616569035, 4779.545943135397, 4801.351817504592, 4801.351817504662, 130783.0, 132925.0, 134827.325863372, 137244.31377843246, 137774.0, 108628.0, 113415.43292283485, 113788.43105784418, 113977.0, 115452.42273788578, 79970.60014700006, 82082.58958705168, 97249.5137524322, 100457.0, 102110.0, 9701.417914925127, 9791.559379827782, 9835.409875407498, 9897.900173490203, 9980.051895069957, 55518.72240638771, 55795.0, 55840.0, 57213.71393143007, 57470.7126464365, 74699.0, 81515.59242203752, 81981.59009204915, 82171.58914205391, 83720.58139709262, 13796.517121900686, 14015.929920350332, 14164.929175354056, 14173.291335433394, 14330.0, 14979.807239662136, 15405.845941540441, 15452.2273886303, 15627.609309766893, 16101.677966440371, 14105.576832695035, 14240.0, 14506.782398263824, 14795.852041479448, 15509.76735348948, 14766.0, 15027.549173524812, 15159.166245856142, 15291.694166116838, 15412.768808467657, 14164.433422663391, 14938.178400188093, 15331.693366132838, 15402.306896189923, 15897.920510397375, 12355.69110772238, 12476.376181190722, 12545.87854606826, 12863.22820630765, 13011.934940325238, 12449.75100497967, 12496.187747796364, 12531.812022819848, 12818.807717884425, 12843.35783210817], "value": 8100.4501338423215, "unit": "score"}], "name": "dromaeo_css", "value": 7490.062810944932, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 111.0, 111.0, 113.0, 113.0, 110.0, 111.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 118.0, 154.0, 122.0, 118.0, 119.0, 118.0, 145.0, 119.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 163.0, 161.0, 160.0, 161.0, 161.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 104.0, 77.0, 76.0, 77.0, 75.0, 97.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 109.0, 82.0, 81.0, 82.0, 100.0, 81.0, 81.0, 107.0], "value": 88.7, "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": [145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 148.0, 147.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 50.0, 52.0, 50.0, 49.0, 50.0, 50.0, 49.0, 50.0, 49.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 58.0, 58.0, 59.0, 58.0, 58.0, 59.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 75.0, 74.0, 75.0, 73.0, 77.0, 81.0, 76.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 122.0, 123.0, 121.0, 119.0, 121.0, 122.0, 122.0, 150.0, 151.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 152.0, 155.0, 152.0, 156.0, 152.0, 152.0, 151.0, 152.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 62.0, 62.0, 63.0, 62.0, 61.0, 62.0, 64.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1455.0000000000002, "alertThreshold": 2.0}]} 09:37:44 INFO - Return code: 0 09:37:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:37:44 INFO - # TBPL SUCCESS # 09:37:44 INFO - Running post-action listener: _resource_record_post_action 09:37:44 INFO - [mozharness: 2016-10-18 16:37:44.556706Z] Finished run-tests step (success) 09:37:44 INFO - Running post-run listener: _resource_record_post_run 09:37:44 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 357896192; Read time: 0; Write time: 198892 09:37:44 INFO - TinderboxPrint: CPU usage
12.9% 09:37:44 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:37:44 INFO - TinderboxPrint: I/O write bytes / time
357,896,192 / 198,892 09:37:44 INFO - TinderboxPrint: CPU idle
8,274.8 (87.0%) 09:37:44 INFO - TinderboxPrint: CPU user
1,186.3 (12.5%) 09:37:44 INFO - TinderboxPrint: Swap in / out
0 / 0 09:37:44 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107343872; Read time: 0; Write time: 62172 09:37:44 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 249999360; Read time: 0; Write time: 136712 09:37:45 INFO - Running post-run listener: _upload_blobber_files 09:37:45 INFO - Blob upload gear active. 09:37:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:37:45 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:37:45 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:37:45 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:37:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:37:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:46 INFO - (blobuploader) - INFO - Done attempting. 09:37:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:49 INFO - (blobuploader) - INFO - Done attempting. 09:37:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:37:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:50 INFO - (blobuploader) - INFO - Done attempting. 09:37:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:50 INFO - Return code: 0 09:37:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0956342235342236d23bab985a2f11afe47876da5e5d8516d3a6592c9ca63dc67fa7ff16975d94a1f2c75845812ddcf4784dae064991a93afe792e4d5f2b8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dbd90c58eb0eb68f0bcd7721a0f78f20ea9932b8a48a181d26c03ad37011ec55078d060cb72fa0abc19afbca90e6e32ea5b3d67b519e9e8b5236c81be8fdc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d050aec11dba0d62aa7e8e19c14528bcda4c6ba4bdafd41752e4145e8a6ce9a7501aa01692b28d720c2674a37c93e9e23b67f998671d20991e2c355c503f04"} 09:37:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:50 INFO - Contents: 09:37:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0956342235342236d23bab985a2f11afe47876da5e5d8516d3a6592c9ca63dc67fa7ff16975d94a1f2c75845812ddcf4784dae064991a93afe792e4d5f2b8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dbd90c58eb0eb68f0bcd7721a0f78f20ea9932b8a48a181d26c03ad37011ec55078d060cb72fa0abc19afbca90e6e32ea5b3d67b519e9e8b5236c81be8fdc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d050aec11dba0d62aa7e8e19c14528bcda4c6ba4bdafd41752e4145e8a6ce9a7501aa01692b28d720c2674a37c93e9e23b67f998671d20991e2c355c503f04"} 09:37:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:50 INFO - Copying logs to upload dir... 09:37:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.276195 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-10-18 09:37:49.673560) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 09:37:49.677135) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 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/0956342235342236d23bab985a2f11afe47876da5e5d8516d3a6592c9ca63dc67fa7ff16975d94a1f2c75845812ddcf4784dae064991a93afe792e4d5f2b8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dbd90c58eb0eb68f0bcd7721a0f78f20ea9932b8a48a181d26c03ad37011ec55078d060cb72fa0abc19afbca90e6e32ea5b3d67b519e9e8b5236c81be8fdc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d050aec11dba0d62aa7e8e19c14528bcda4c6ba4bdafd41752e4145e8a6ce9a7501aa01692b28d720c2674a37c93e9e23b67f998671d20991e2c355c503f04"} build_url:https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/R8U7EzmFQO-8gn5Luwsbiw/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/0956342235342236d23bab985a2f11afe47876da5e5d8516d3a6592c9ca63dc67fa7ff16975d94a1f2c75845812ddcf4784dae064991a93afe792e4d5f2b8b02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dbd90c58eb0eb68f0bcd7721a0f78f20ea9932b8a48a181d26c03ad37011ec55078d060cb72fa0abc19afbca90e6e32ea5b3d67b519e9e8b5236c81be8fdc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d2d050aec11dba0d62aa7e8e19c14528bcda4c6ba4bdafd41752e4145e8a6ce9a7501aa01692b28d720c2674a37c93e9e23b67f998671d20991e2c355c503f04"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 09:37:49.702632) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:37:49.702964) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1476807432.978356-1101954764 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 09:37:49.786654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 09:37:49.786992) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 09:37:49.787321) ========= ========= Total master_lag: 0.17 =========