builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1477564945.56 results: success (0) buildid: 20161027004020 builduid: 70162877078a48d991615c92e830a30b revision: 88cbb2726969ee5a4204ae9df3025c39c864de15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.563073) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.563594) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.563968) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.578813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.579127) ========= 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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-27 03:42:25-- 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% 46.5M=0s 2016-10-27 03:42:25 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114332 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.707858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.708213) ========= 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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019623 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:25.759017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 03:42:25.759396) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 88cbb2726969ee5a4204ae9df3025c39c864de15 --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 88cbb2726969ee5a4204ae9df3025c39c864de15 --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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-27 03:42:26,046 truncating revision to first 12 chars 2016-10-27 03:42:26,046 Setting DEBUG logging. 2016-10-27 03:42:26,046 attempt 1/10 2016-10-27 03:42:26,046 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/88cbb2726969?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-27 03:42:27,008 unpacking tar archive at: mozilla-aurora-88cbb2726969/testing/mozharness/ program finished with exit code 0 elapsedTime=1.268536 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-27 03:42:27.042255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:27.042642) ========= script_repo_revision: 88cbb2726969ee5a4204ae9df3025c39c864de15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:27.043183) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:27.043687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-27 03:42:27.061880) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-27 03:42:27.062540) ========= /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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:42:27 INFO - MultiFileLogger online at 20161027 03:42:27 in /builds/slave/test 03:42:27 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 03:42:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:42:27 INFO - {'append_to_log': False, 03:42:27 INFO - 'base_work_dir': '/builds/slave/test', 03:42:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:42:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:42:27 INFO - 'branch': 'Mozilla-Aurora', 03:42:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:42:27 INFO - 'config_files': ('talos/linux_config.py',), 03:42:27 INFO - 'default_actions': ('clobber', 03:42:27 INFO - 'read-buildbot-config', 03:42:27 INFO - 'download-and-extract', 03:42:27 INFO - 'populate-webroot', 03:42:27 INFO - 'create-virtualenv', 03:42:27 INFO - 'install', 03:42:27 INFO - 'run-tests'), 03:42:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:42:27 INFO - 'download_minidump_stackwalk': True, 03:42:27 INFO - 'download_symbols': 'ondemand', 03:42:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:42:27 INFO - 'tooltool.py': '/tools/tooltool.py', 03:42:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:42:27 INFO - '/tools/misc-python/virtualenv.py')}, 03:42:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:42:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:42:27 INFO - 'installer_path': 'installer.exe', 03:42:27 INFO - 'log_level': 'info', 03:42:27 INFO - 'log_name': 'talos', 03:42:27 INFO - 'log_to_console': True, 03:42:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:42:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:42:27 INFO - 'opt_config_files': (), 03:42:27 INFO - 'pip_index': False, 03:42:27 INFO - 'sps_profile': False, 03:42:27 INFO - 'sps_profile_interval': 0, 03:42:27 INFO - 'suite': 'dromaeojs', 03:42:27 INFO - 'system_bits': '32', 03:42:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:42:27 INFO - 'title': 'talos-linux64-ix-036', 03:42:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:42:27 INFO - 'use_talos_json': True, 03:42:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:42:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:42:27 INFO - 'work_dir': 'build'} 03:42:27 INFO - [mozharness: 2016-10-27 10:42:27.451690Z] Running clobber step. 03:42:27 INFO - Running pre-action listener: _resource_record_pre_action 03:42:27 INFO - Running main action method: clobber 03:42:27 INFO - rmtree: /builds/slave/test/build 03:42:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:42:27 INFO - Running post-action listener: _resource_record_post_action 03:42:27 INFO - [mozharness: 2016-10-27 10:42:27.911133Z] Finished clobber step (success) 03:42:27 INFO - [mozharness: 2016-10-27 10:42:27.911233Z] Running read-buildbot-config step. 03:42:27 INFO - Running pre-action listener: _resource_record_pre_action 03:42:27 INFO - Running main action method: read_buildbot_config 03:42:27 INFO - Using buildbot properties: 03:42:27 INFO - { 03:42:27 INFO - "project": "", 03:42:27 INFO - "product": "firefox", 03:42:27 INFO - "script_repo_revision": "production", 03:42:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:42:27 INFO - "repository": "", 03:42:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:42:27 INFO - "buildid": "20161027004020", 03:42:27 INFO - "stage_platform": "linux64-pgo", 03:42:27 INFO - "basedir": "/builds/slave/test", 03:42:27 INFO - "nightly_build": "True", 03:42:27 INFO - "buildnumber": 557, 03:42:27 INFO - "slavename": "talos-linux64-ix-036", 03:42:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:42:27 INFO - "platform": "ubuntu64_hw", 03:42:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:42:27 INFO - "branch": "mozilla-aurora", 03:42:27 INFO - "repo_path": "releases/mozilla-aurora", 03:42:27 INFO - "revision": "88cbb2726969ee5a4204ae9df3025c39c864de15", 03:42:27 INFO - "pgo_build": "True", 03:42:27 INFO - "builduid": "70162877078a48d991615c92e830a30b", 03:42:27 INFO - "slavebuilddir": "test" 03:42:27 INFO - } 03:42:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 03:42:27 INFO - Running post-action listener: _resource_record_post_action 03:42:27 INFO - [mozharness: 2016-10-27 10:42:27.913748Z] Finished read-buildbot-config step (success) 03:42:27 INFO - [mozharness: 2016-10-27 10:42:27.913838Z] Running download-and-extract step. 03:42:27 INFO - Running pre-action listener: _resource_record_pre_action 03:42:27 INFO - Running main action method: download_and_extract 03:42:27 INFO - mkdir: /builds/slave/test/build/tests 03:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:42:27 INFO - trying https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:42:27 INFO - Downloading https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/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 03:42:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/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 03:42:28 INFO - Downloaded 1361 bytes. 03:42:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 03:42:28 INFO - Using the following test package requirements: 03:42:28 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:42:28 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:42:28 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:42:28 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'jsshell-linux-x86_64.zip'], 03:42:28 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:42:28 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 03:42:28 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:42:28 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:42:28 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:42:28 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 03:42:28 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:42:28 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:42:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:42:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:42:28 INFO - Fetch https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:42:29 INFO - Expected file size: 21892463 03:42:33 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 03:42:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 03:42:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:42:33 INFO - Fetch https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 03:42:34 INFO - Expected file size: 21892463 03:42:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:42:38 INFO - Fetch https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 03:42:42 INFO - Expected file size: 14414081 03:42: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')]} 03:42:42 INFO - trying https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:42:42 INFO - Downloading https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/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 03:42:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/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 03:42:43 INFO - Downloaded 63945403 bytes. 03:42:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:42:43 INFO - mkdir: /builds/slave/test/properties 03:42:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:42:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:42:43 INFO - Contents: 03:42:43 INFO - build_url:https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 03:42:44 INFO - Running post-action listener: _resource_record_post_action 03:42:44 INFO - Running post-action listener: set_extra_try_arguments 03:42:44 INFO - [mozharness: 2016-10-27 10:42:44.561667Z] Finished download-and-extract step (success) 03:42:44 INFO - [mozharness: 2016-10-27 10:42:44.561895Z] Running populate-webroot step. 03:42:44 INFO - Running pre-action listener: _resource_record_pre_action 03:42:44 INFO - Running main action method: populate_webroot 03:42:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:42:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:42:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:42:44 INFO - '--tppagecycles', 03:42:44 INFO - '7'], 03:42:44 INFO - 'tests': ['tsvgm']}}, 03:42:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:42:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:42:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:42:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:42:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['damp', 'tps']}, 03:42:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'tests': ['damp', 'tps']}, 03:42:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['dromaeo_dom']}, 03:42:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:42:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['basic_compositor_video']}, 03:42:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:42:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'other-e10s': {'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['a11yr', 03:42:44 INFO - 'ts_paint', 03:42:44 INFO - 'tpaint', 03:42:44 INFO - 'sessionrestore', 03:42:44 INFO - 'sessionrestore_no_auto_restore', 03:42:44 INFO - 'tabpaint']}, 03:42:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['tsvgx', 03:42:44 INFO - 'tsvgr_opacity', 03:42:44 INFO - 'tart', 03:42:44 INFO - 'tscrollx', 03:42:44 INFO - 'cart']}, 03:42:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:42:44 INFO - 'tsvgr_opacity', 03:42:44 INFO - 'tart', 03:42:44 INFO - 'tscrollx', 03:42:44 INFO - 'cart']}, 03:42:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'talos_options': ['--disable-e10s'], 03:42:44 INFO - 'tests': ['tp5o']}, 03:42:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'tests': ['tp5o']}, 03:42:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'talos_options': ['--disable-e10s', 03:42:44 INFO - '--xperf_path', 03:42:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:44 INFO - 'tests': ['tp5n']}, 03:42:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:44 INFO - 'talos_options': ['--xperf_path', 03:42:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:44 INFO - 'tests': ['tp5n']}}, 03:42:44 INFO - 'talos.zip': {'path': '', 03:42:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:42:44 INFO - Running post-action listener: _resource_record_post_action 03:42:44 INFO - [mozharness: 2016-10-27 10:42:44.583246Z] Finished populate-webroot step (success) 03:42:44 INFO - [mozharness: 2016-10-27 10:42:44.583334Z] Running create-virtualenv step. 03:42:44 INFO - Running pre-action listener: _resource_record_pre_action 03:42:44 INFO - Running main action method: create_virtualenv 03:42:44 INFO - Creating virtualenv /builds/slave/test/build/venv 03:42:44 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 03:42:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:42:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:42:44 INFO - Using real prefix '/usr' 03:42:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:42:45 INFO - Installing distribute.............................................................................................................................................................................................done. 03:42:47 INFO - Installing pip.................done. 03:42:47 INFO - Return code: 0 03:42:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:42:47 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')]} 03:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:47 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')]} 03:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:47 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:47 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 03:42:47 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 03:42:47 INFO - Using env: {'DISPLAY': ':0', 03:42:47 INFO - 'HOME': '/home/cltbld', 03:42:47 INFO - 'LANG': 'en_US.UTF-8', 03:42:47 INFO - 'LANGUAGE': 'en_US:en', 03:42:47 INFO - 'LOGNAME': 'cltbld', 03:42:47 INFO - 'MAIL': '/var/mail/cltbld', 03:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:47 INFO - 'MOZ_NO_REMOTE': '1', 03:42:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:47 INFO - 'NO_EM_RESTART': '1', 03:42:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:47 INFO - 'PWD': '/builds/slave/test', 03:42:47 INFO - 'SHELL': '/bin/bash', 03:42:47 INFO - 'SHLVL': '1', 03:42:47 INFO - 'TERM': 'linux', 03:42:47 INFO - 'TMOUT': '86400', 03:42:47 INFO - 'USER': 'cltbld', 03:42:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:47 INFO - '_': '/tools/buildbot/bin/python'} 03:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:47 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 03:42:47 INFO - Cleaning up... 03:42:47 INFO - Return code: 0 03:42:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:42:47 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')]} 03:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:47 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')]} 03:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:47 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:47 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 03:42:47 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 03:42:47 INFO - Using env: {'DISPLAY': ':0', 03:42:47 INFO - 'HOME': '/home/cltbld', 03:42:47 INFO - 'LANG': 'en_US.UTF-8', 03:42:47 INFO - 'LANGUAGE': 'en_US:en', 03:42:47 INFO - 'LOGNAME': 'cltbld', 03:42:47 INFO - 'MAIL': '/var/mail/cltbld', 03:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:47 INFO - 'MOZ_NO_REMOTE': '1', 03:42:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:47 INFO - 'NO_EM_RESTART': '1', 03:42:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:47 INFO - 'PWD': '/builds/slave/test', 03:42:47 INFO - 'SHELL': '/bin/bash', 03:42:47 INFO - 'SHLVL': '1', 03:42:47 INFO - 'TERM': 'linux', 03:42:47 INFO - 'TMOUT': '86400', 03:42:47 INFO - 'USER': 'cltbld', 03:42:47 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:47 INFO - '_': '/tools/buildbot/bin/python'} 03:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:47 INFO - Downloading/unpacking psutil>=3.1.1 03:42:47 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 03:42:47 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 03:42:47 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 03:42:47 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 03:42:47 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 03:42:47 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 03:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:49 INFO - Installing collected packages: psutil 03:42:49 INFO - Running setup.py install for psutil 03:42:49 INFO - building 'psutil._psutil_linux' extension 03:42:49 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 03:42:49 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 03:42:49 INFO - building 'psutil._psutil_posix' extension 03:42:49 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 03:42:50 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 03:42:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:50 INFO - Successfully installed psutil 03:42:50 INFO - Cleaning up... 03:42:50 INFO - Return code: 0 03:42:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:42:50 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')]} 03:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:50 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')]} 03:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:50 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:50 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 03:42:50 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 03:42:50 INFO - Using env: {'DISPLAY': ':0', 03:42:50 INFO - 'HOME': '/home/cltbld', 03:42:50 INFO - 'LANG': 'en_US.UTF-8', 03:42:50 INFO - 'LANGUAGE': 'en_US:en', 03:42:50 INFO - 'LOGNAME': 'cltbld', 03:42:50 INFO - 'MAIL': '/var/mail/cltbld', 03:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:50 INFO - 'MOZ_NO_REMOTE': '1', 03:42:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:50 INFO - 'NO_EM_RESTART': '1', 03:42:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:50 INFO - 'PWD': '/builds/slave/test', 03:42:50 INFO - 'SHELL': '/bin/bash', 03:42:50 INFO - 'SHLVL': '1', 03:42:50 INFO - 'TERM': 'linux', 03:42:50 INFO - 'TMOUT': '86400', 03:42:50 INFO - 'USER': 'cltbld', 03:42:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:50 INFO - '_': '/tools/buildbot/bin/python'} 03:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:42: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 03:42: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 03:42: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 03:42: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 03:42: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 03:42: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 03:42:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:42:52 INFO - Installing collected packages: mozsystemmonitor 03:42:52 INFO - Running setup.py install for mozsystemmonitor 03:42:52 INFO - Successfully installed mozsystemmonitor 03:42:52 INFO - Cleaning up... 03:42:52 INFO - Return code: 0 03:42:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:42: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')]} 03:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42: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')]} 03:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:52 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:52 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 03:42:52 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 03:42:52 INFO - Using env: {'DISPLAY': ':0', 03:42:52 INFO - 'HOME': '/home/cltbld', 03:42:52 INFO - 'LANG': 'en_US.UTF-8', 03:42:52 INFO - 'LANGUAGE': 'en_US:en', 03:42:52 INFO - 'LOGNAME': 'cltbld', 03:42:52 INFO - 'MAIL': '/var/mail/cltbld', 03:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:52 INFO - 'MOZ_NO_REMOTE': '1', 03:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:52 INFO - 'NO_EM_RESTART': '1', 03:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:52 INFO - 'PWD': '/builds/slave/test', 03:42:52 INFO - 'SHELL': '/bin/bash', 03:42:52 INFO - 'SHLVL': '1', 03:42:52 INFO - 'TERM': 'linux', 03:42:52 INFO - 'TMOUT': '86400', 03:42:52 INFO - 'USER': 'cltbld', 03:42:52 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:52 INFO - '_': '/tools/buildbot/bin/python'} 03:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:42:52 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 03:42:52 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 03:42:52 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 03:42:52 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 03:42:52 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 03:42:52 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 03:42:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:42:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:54 INFO - Downloading functools32-3.2.3-2.tar.gz 03:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:42:54 INFO - warning: no files found matching '*.txt' 03:42:54 INFO - no previously-included directories found matching 'build' 03:42:54 INFO - no previously-included directories found matching 'dist' 03:42:54 INFO - no previously-included directories found matching '.git*' 03:42:54 INFO - Installing collected packages: jsonschema, functools32 03:42:54 INFO - Running setup.py install for functools32 03:42:55 INFO - warning: no files found matching '*.txt' 03:42:55 INFO - no previously-included directories found matching 'build' 03:42:55 INFO - no previously-included directories found matching 'dist' 03:42:55 INFO - no previously-included directories found matching '.git*' 03:42:55 INFO - Successfully installed jsonschema functools32 03:42:55 INFO - Cleaning up... 03:42:55 INFO - Return code: 0 03:42:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:55 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:55 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 03:42:55 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 03:42:55 INFO - Using env: {'DISPLAY': ':0', 03:42:55 INFO - 'HOME': '/home/cltbld', 03:42:55 INFO - 'LANG': 'en_US.UTF-8', 03:42:55 INFO - 'LANGUAGE': 'en_US:en', 03:42:55 INFO - 'LOGNAME': 'cltbld', 03:42:55 INFO - 'MAIL': '/var/mail/cltbld', 03:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:55 INFO - 'MOZ_NO_REMOTE': '1', 03:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:55 INFO - 'NO_EM_RESTART': '1', 03:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:55 INFO - 'PWD': '/builds/slave/test', 03:42:55 INFO - 'SHELL': '/bin/bash', 03:42:55 INFO - 'SHLVL': '1', 03:42:55 INFO - 'TERM': 'linux', 03:42:55 INFO - 'TMOUT': '86400', 03:42:55 INFO - 'USER': 'cltbld', 03:42:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:55 INFO - '_': '/tools/buildbot/bin/python'} 03:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:42:55 INFO - Cleaning up... 03:42:55 INFO - Return code: 0 03:42:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:55 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:55 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 03:42:55 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 03:42:55 INFO - Using env: {'DISPLAY': ':0', 03:42:55 INFO - 'HOME': '/home/cltbld', 03:42:55 INFO - 'LANG': 'en_US.UTF-8', 03:42:55 INFO - 'LANGUAGE': 'en_US:en', 03:42:55 INFO - 'LOGNAME': 'cltbld', 03:42:55 INFO - 'MAIL': '/var/mail/cltbld', 03:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:55 INFO - 'MOZ_NO_REMOTE': '1', 03:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:55 INFO - 'NO_EM_RESTART': '1', 03:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:55 INFO - 'PWD': '/builds/slave/test', 03:42:55 INFO - 'SHELL': '/bin/bash', 03:42:55 INFO - 'SHLVL': '1', 03:42:55 INFO - 'TERM': 'linux', 03:42:55 INFO - 'TMOUT': '86400', 03:42:55 INFO - 'USER': 'cltbld', 03:42:55 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:55 INFO - '_': '/tools/buildbot/bin/python'} 03:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:55 INFO - Downloading/unpacking blobuploader==1.2.4 03:42:55 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 03:42:55 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 03:42:55 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 03:42:55 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 03:42:55 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 03:42:55 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 03:42:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:42:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:42:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:58 INFO - Downloading docopt-0.6.1.tar.gz 03:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:42:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:42:58 INFO - Running setup.py install for blobuploader 03:42:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:42:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:42:58 INFO - Running setup.py install for requests 03:42:58 INFO - Running setup.py install for docopt 03:42:58 INFO - Successfully installed blobuploader requests docopt 03:42:58 INFO - Cleaning up... 03:42:58 INFO - Return code: 0 03:42:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:42:58 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')]} 03:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:58 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')]} 03:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:58 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:42:58 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 03:42:58 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 03:42:58 INFO - Using env: {'DISPLAY': ':0', 03:42:58 INFO - 'HOME': '/home/cltbld', 03:42:58 INFO - 'LANG': 'en_US.UTF-8', 03:42:58 INFO - 'LANGUAGE': 'en_US:en', 03:42:58 INFO - 'LOGNAME': 'cltbld', 03:42:58 INFO - 'MAIL': '/var/mail/cltbld', 03:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:58 INFO - 'MOZ_NO_REMOTE': '1', 03:42:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:58 INFO - 'NO_EM_RESTART': '1', 03:42:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:58 INFO - 'PWD': '/builds/slave/test', 03:42:58 INFO - 'SHELL': '/bin/bash', 03:42:58 INFO - 'SHLVL': '1', 03:42:58 INFO - 'TERM': 'linux', 03:42:58 INFO - 'TMOUT': '86400', 03:42:58 INFO - 'USER': 'cltbld', 03:42:58 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:58 INFO - '_': '/tools/buildbot/bin/python'} 03:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:42:59 INFO - Running setup.py (path:/tmp/pip-2JL0yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:42:59 INFO - Running setup.py (path:/tmp/pip-wFem8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:42:59 INFO - Running setup.py (path:/tmp/pip-pKwH03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:42:59 INFO - Running setup.py (path:/tmp/pip-lB7vFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:42:59 INFO - Running setup.py (path:/tmp/pip-G8bxuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:42:59 INFO - Running setup.py (path:/tmp/pip-WEts5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:42:59 INFO - Running setup.py (path:/tmp/pip-4PyTpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:42:59 INFO - Running setup.py (path:/tmp/pip-OOuhQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:42:59 INFO - Running setup.py (path:/tmp/pip-147oeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:42:59 INFO - Running setup.py (path:/tmp/pip-Cg7sae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:42:59 INFO - Running setup.py (path:/tmp/pip-S0SoYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:42:59 INFO - Running setup.py (path:/tmp/pip-6Faup6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:42:59 INFO - Running setup.py (path:/tmp/pip-L9XHVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:00 INFO - Running setup.py (path:/tmp/pip-Vj1Eaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:00 INFO - Running setup.py (path:/tmp/pip-bmFPDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:00 INFO - Running setup.py (path:/tmp/pip-TYhOxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:00 INFO - Running setup.py (path:/tmp/pip-GABRxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:00 INFO - Running setup.py install for manifestparser 03:43:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:00 INFO - Running setup.py install for mozcrash 03:43:00 INFO - Running setup.py install for mozdebug 03:43:00 INFO - Running setup.py install for mozdevice 03:43:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:00 INFO - Running setup.py install for mozfile 03:43:00 INFO - Running setup.py install for mozhttpd 03:43:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:00 INFO - Running setup.py install for mozinfo 03:43:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for mozInstall 03:43:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for mozleak 03:43:01 INFO - Running setup.py install for mozlog 03:43:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for moznetwork 03:43:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for mozprocess 03:43:01 INFO - Running setup.py install for mozprofile 03:43:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for mozrunner 03:43:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:01 INFO - Running setup.py install for mozscreenshot 03:43:01 INFO - Running setup.py install for moztest 03:43:01 INFO - Running setup.py install for mozversion 03:43:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:02 INFO - Cleaning up... 03:43:02 INFO - Return code: 0 03:43:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:02 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:43:02 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 03:43:02 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 03:43:02 INFO - Using env: {'DISPLAY': ':0', 03:43:02 INFO - 'HOME': '/home/cltbld', 03:43:02 INFO - 'LANG': 'en_US.UTF-8', 03:43:02 INFO - 'LANGUAGE': 'en_US:en', 03:43:02 INFO - 'LOGNAME': 'cltbld', 03:43:02 INFO - 'MAIL': '/var/mail/cltbld', 03:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:02 INFO - 'MOZ_NO_REMOTE': '1', 03:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:02 INFO - 'NO_EM_RESTART': '1', 03:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:02 INFO - 'PWD': '/builds/slave/test', 03:43:02 INFO - 'SHELL': '/bin/bash', 03:43:02 INFO - 'SHLVL': '1', 03:43:02 INFO - 'TERM': 'linux', 03:43:02 INFO - 'TMOUT': '86400', 03:43:02 INFO - 'USER': 'cltbld', 03:43:02 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:02 INFO - '_': '/tools/buildbot/bin/python'} 03:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:02 INFO - Running setup.py (path:/tmp/pip-tttUTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:02 INFO - Running setup.py (path:/tmp/pip-gQdJZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:02 INFO - Running setup.py (path:/tmp/pip-mPALel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:02 INFO - Running setup.py (path:/tmp/pip-Xgsuhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:02 INFO - Running setup.py (path:/tmp/pip-7O8s9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:02 INFO - Running setup.py (path:/tmp/pip-y4HRvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:02 INFO - Running setup.py (path:/tmp/pip-hrDr3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:02 INFO - Running setup.py (path:/tmp/pip-ZvJNbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:02 INFO - Running setup.py (path:/tmp/pip-pbc56Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:02 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)) 03:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:02 INFO - Running setup.py (path:/tmp/pip-c1ztWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:03 INFO - Running setup.py (path:/tmp/pip-WaY9DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:03 INFO - Running setup.py (path:/tmp/pip-38geAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:03 INFO - Running setup.py (path:/tmp/pip-5_grBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:03 INFO - Running setup.py (path:/tmp/pip-Z8Cvct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:03 INFO - Running setup.py (path:/tmp/pip-Kp8rWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:03 INFO - Running setup.py (path:/tmp/pip-MTUr5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:03 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)) 03:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:03 INFO - Running setup.py (path:/tmp/pip-zEmZf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:03 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)) 03:43:03 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)) 03:43:03 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)) 03:43:03 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)) 03:43:03 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)) 03:43:03 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)) 03:43:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - Downloading blessings-1.6.tar.gz 03:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:05 INFO - Installing collected packages: blessings 03:43:05 INFO - Running setup.py install for blessings 03:43:05 INFO - Successfully installed blessings 03:43:05 INFO - Cleaning up... 03:43:05 INFO - Return code: 0 03:43:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:05 INFO - Reading from file tmpfile_stdout 03:43:05 INFO - Current package versions: 03:43:05 INFO - argparse == 1.2.1 03:43:05 INFO - blessings == 1.6 03:43:05 INFO - blobuploader == 1.2.4 03:43:05 INFO - docopt == 0.6.1 03:43:05 INFO - functools32 == 3.2.3-2 03:43:05 INFO - jsonschema == 2.5.1 03:43:05 INFO - manifestparser == 1.1 03:43:05 INFO - mozInstall == 1.12 03:43:05 INFO - mozcrash == 0.17 03:43:05 INFO - mozdebug == 0.1 03:43:05 INFO - mozdevice == 0.48 03:43:05 INFO - mozfile == 1.2 03:43:05 INFO - mozhttpd == 0.7 03:43:05 INFO - mozinfo == 0.9 03:43:05 INFO - mozleak == 0.1 03:43:05 INFO - mozlog == 3.4 03:43:05 INFO - moznetwork == 0.27 03:43:05 INFO - mozprocess == 0.23 03:43:05 INFO - mozprofile == 0.28 03:43:05 INFO - mozrunner == 6.12 03:43:05 INFO - mozscreenshot == 0.1 03:43:05 INFO - mozsystemmonitor == 0.3 03:43:05 INFO - moztest == 0.7 03:43:05 INFO - mozversion == 1.4 03:43:05 INFO - psutil == 3.1.1 03:43:05 INFO - requests == 1.2.3 03:43:05 INFO - wsgiref == 0.1.2 03:43:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:05 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')]} 03:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:05 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')]} 03:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:05 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:43:05 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 03:43:05 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 03:43:05 INFO - Using env: {'DISPLAY': ':0', 03:43:05 INFO - 'HOME': '/home/cltbld', 03:43:05 INFO - 'LANG': 'en_US.UTF-8', 03:43:05 INFO - 'LANGUAGE': 'en_US:en', 03:43:05 INFO - 'LOGNAME': 'cltbld', 03:43:05 INFO - 'MAIL': '/var/mail/cltbld', 03:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:05 INFO - 'MOZ_NO_REMOTE': '1', 03:43:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:05 INFO - 'NO_EM_RESTART': '1', 03:43:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:05 INFO - 'PWD': '/builds/slave/test', 03:43:05 INFO - 'SHELL': '/bin/bash', 03:43:05 INFO - 'SHLVL': '1', 03:43:05 INFO - 'TERM': 'linux', 03:43:05 INFO - 'TMOUT': '86400', 03:43:05 INFO - 'USER': 'cltbld', 03:43:05 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:05 INFO - '_': '/tools/buildbot/bin/python'} 03:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 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)) 03:43:06 INFO - Cleaning up... 03:43:06 INFO - Return code: 0 03:43:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:06 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 0x7fcb48720f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1efeca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', '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 03:43:06 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 03:43:06 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 03:43:06 INFO - Using env: {'DISPLAY': ':0', 03:43:06 INFO - 'HOME': '/home/cltbld', 03:43:06 INFO - 'LANG': 'en_US.UTF-8', 03:43:06 INFO - 'LANGUAGE': 'en_US:en', 03:43:06 INFO - 'LOGNAME': 'cltbld', 03:43:06 INFO - 'MAIL': '/var/mail/cltbld', 03:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:06 INFO - 'MOZ_NO_REMOTE': '1', 03:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:06 INFO - 'NO_EM_RESTART': '1', 03:43:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:06 INFO - 'PWD': '/builds/slave/test', 03:43:06 INFO - 'SHELL': '/bin/bash', 03:43:06 INFO - 'SHLVL': '1', 03:43:06 INFO - 'TERM': 'linux', 03:43:06 INFO - 'TMOUT': '86400', 03:43:06 INFO - 'USER': 'cltbld', 03:43:06 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:06 INFO - '_': '/tools/buildbot/bin/python'} 03:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:06 INFO - Cleaning up... 03:43:06 INFO - Return code: 0 03:43:06 INFO - Running post-action listener: _resource_record_post_action 03:43:06 INFO - Running post-action listener: _start_resource_monitoring 03:43:06 INFO - Starting resource monitoring. 03:43:06 INFO - [mozharness: 2016-10-27 10:43:06.411221Z] Finished create-virtualenv step (success) 03:43:06 INFO - [mozharness: 2016-10-27 10:43:06.411671Z] Running install step. 03:43:06 INFO - Running pre-action listener: _resource_record_pre_action 03:43:06 INFO - Running main action method: install 03:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:06 INFO - Reading from file tmpfile_stdout 03:43:06 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:06 INFO - Reading from file tmpfile_stdout 03:43:06 INFO - Output received: 03:43:06 INFO - Usage: mozinstall [options] installer 03:43:06 INFO - Options: 03:43:06 INFO - -h, --help show this help message and exit 03:43:06 INFO - -d DEST, --destination=DEST 03:43:06 INFO - Directory to install application into. [default: 03:43:06 INFO - "/builds/slave/test"] 03:43:06 INFO - --app=APP Application being installed. [default: firefox] 03:43:06 INFO - mkdir: /builds/slave/test/build/application 03:43:06 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'] 03:43:06 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 03:43:22 INFO - Reading from file tmpfile_stdout 03:43:22 INFO - Output received: 03:43:22 INFO - /builds/slave/test/build/application/firefox/firefox 03:43:22 INFO - Running post-action listener: _resource_record_post_action 03:43:22 INFO - [mozharness: 2016-10-27 10:43:22.639229Z] Finished install step (success) 03:43:22 INFO - [mozharness: 2016-10-27 10:43:22.639472Z] Running run-tests step. 03:43:22 INFO - Running pre-action listener: _resource_record_pre_action 03:43:22 INFO - Running main action method: run_tests 03:43:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:43:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:43:22 INFO - Python 2.7.3 03:43:22 INFO - Return code: 0 03:43:22 INFO - grabbing minidump binary from tooltool 03:43:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:22 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 0x1efbf60>, '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 0x1efcd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efd1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:43:22 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 03:43:22 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 03:43:22 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 03:43:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:43:22 INFO - Return code: 0 03:43:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:43:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:43:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:43:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:43:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:43:22 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/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 03:43:22 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/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 03:43:22 INFO - Using env: {'DISPLAY': ':0', 03:43:22 INFO - 'HOME': '/home/cltbld', 03:43:22 INFO - 'LANG': 'en_US.UTF-8', 03:43:22 INFO - 'LANGUAGE': 'en_US:en', 03:43:22 INFO - 'LOGNAME': 'cltbld', 03:43:22 INFO - 'MAIL': '/var/mail/cltbld', 03:43:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:43:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:22 INFO - 'MOZ_NO_REMOTE': '1', 03:43:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:43:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:22 INFO - 'NO_EM_RESTART': '1', 03:43:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:22 INFO - 'PWD': '/builds/slave/test', 03:43:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:43:22 INFO - 'SHELL': '/bin/bash', 03:43:22 INFO - 'SHLVL': '1', 03:43:22 INFO - 'TERM': 'linux', 03:43:22 INFO - 'TMOUT': '86400', 03:43:22 INFO - 'USER': 'cltbld', 03:43:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188', 03:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:22 INFO - '_': '/tools/buildbot/bin/python'} 03:43:22 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/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 03:43:22 INFO - mozversion application_buildid: 20161027004020 03:43:22 INFO - mozversion application_changeset: 88cbb2726969ee5a4204ae9df3025c39c864de15 03:43:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:43:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:43:22 INFO - mozversion application_name: Firefox 03:43:22 INFO - mozversion application_remotingname: firefox-dev 03:43:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:22 INFO - mozversion application_vendor: Mozilla 03:43:22 INFO - mozversion application_version: 51.0a2 03:43:22 INFO - mozversion platform_buildid: 20161027004020 03:43:22 INFO - mozversion platform_changeset: 88cbb2726969ee5a4204ae9df3025c39c864de15 03:43:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:22 INFO - mozversion platform_version: 51.0a2 03:43:22 INFO - using testdate: 1477565002 03:43:22 INFO - actual date: 1477565002 03:43:22 INFO - starting webserver on 'localhost:40658' 03:43:22 INFO - SUITE-START | Running 2 tests 03:43:22 INFO - TEST-START | dromaeo_css 03:43:22 INFO - operating with platform_type : linux_ 03:43:22 INFO - Initialising browser for dromaeo_css test... 03:43:22 INFO - TEST-INFO | started process 12523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmW285/profile http://localhost:40658/getInfo.html) 03:43:27 INFO - PROCESS | 12523 | __metrics Screen width/height:1600/1200 03:43:27 INFO - PROCESS | 12523 | colorDepth:24 03:43:27 INFO - PROCESS | 12523 | Browser inner width/height: 1024/697 03:43:27 INFO - PROCESS | 12523 | __metrics 03:43:27 INFO - PROCESS | 12523 | 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" 03:43:27 INFO - PROCESS | 12523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:43:28 INFO - PROCESS | 12523 | console.error: PushService: 03:43:28 INFO - PROCESS | 12523 | stateChangeProcessEnqueue: Error transitioning state 03:43:28 INFO - PROCESS | 12523 | UnknownError 03:43:28 INFO - TEST-INFO | 12523: exit 0 03:43:28 INFO - Browser initialized. 03:43:28 INFO - Running cycle 1/1 for dromaeo_css test... 03:43:28 INFO - TEST-INFO | started process 12617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVmW285/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:40 INFO - PROCESS | 12617 | RSS: Main: 144461824 03:43:40 INFO - PROCESS | 12617 | 03:44:31 INFO - PROCESS | 12617 | 1477565071540 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] 03:46:40 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40658/tests/dromaeo/cssquery-ext.html) 03:46:41 INFO - PROCESS | 12617 | RSS: Main: 282869760 03:46:41 INFO - PROCESS | 12617 | 03:49:44 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-ext.html (next: http://localhost:40658/tests/dromaeo/cssquery-jquery.html) 03:49:44 INFO - PROCESS | 12617 | RSS: Main: 229576704 03:49:44 INFO - PROCESS | 12617 | 03:52:46 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40658/tests/dromaeo/cssquery-mootools.html) 03:52:47 INFO - PROCESS | 12617 | RSS: Main: 209653760 03:52:47 INFO - PROCESS | 12617 | 03:55:49 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40658/tests/dromaeo/cssquery-prototype.html) 03:55:49 INFO - PROCESS | 12617 | RSS: Main: 227004416 03:55:49 INFO - PROCESS | 12617 | 03:58:52 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40658/tests/dromaeo/cssquery-yui.html) 03:58:52 INFO - PROCESS | 12617 | RSS: Main: 176996352 03:58:52 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | Cycle 1(1): loaded http://localhost:40658/tests/dromaeo/cssquery-yui.html (next: http://localhost:40658/tests/dromaeo/cssquery-dojo.html) 04:01:55 INFO - PROCESS | 12617 | RSS: Main: 267595776 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | __start_tp_report 04:01:55 INFO - PROCESS | 12617 | _x_x_mozilla_page_load 04:01:55 INFO - PROCESS | 12617 | _x_x_mozilla_page_load_details 04:01:55 INFO - PROCESS | 12617 | |i|pagename|runs| 04:01:55 INFO - PROCESS | 12617 | |0;dojo.html;3106.549550315204;3148.9842550787243;3153.7477001839857;3171.7621178411623;3191.840407979602;5462.4810642988905;5566.888662226753;5573.47052030057;5574.136008918619;5577;5568.053430916743;5654.434556544344;5656.660600363981;5664.0937450008005;5685.488306052454;5111.795528178868;5116.104681680691;5149.742512874342;5185.818496352609;5194.428612852583;4954.281629163787;5138.8715282118055;5203.635745497817;5206.661566998152;5211.609129315298;4940.2342636891335;5053.797848086072;5059.266406371074;5106.412762532319;5127.564157046656;5066;5089.541941225289;5096.974515127437;5150.871228219287;5176;4972.825951091694;5041.218611115246;5082.364704411949;5087.440381558025;5110.182370820651;5322.2548843161985;5355.250264962909;5411.702356370398;5474.42518535556;5477.370102438231;5872.970635146797;5904.675242861641;5914.172015917775;5923.992921203406;5928.28860536734;5944.048952167633;5963.13534537494;5965.343812180657;5967.6419414835245;5970.641761494324;5317.388500322435;5368.355797304349;5504.614676972654;5556.138798486241;5585.972070139624;6188.81433556994;6193.473554747807;6194.318624951252;6202.875942481125;6204.472619827366;5923.052311630118;5924.437178468039;5930.347661757203;5951.702414879325;5971.701414929236;5710.914336284959;5834.212381328466;5857;5928.140419639129;6033.879322413527;5941.613795103304;5955.37468565803;5968.074948383008;6007.909881351783;6019.608725432833;5967.522598192134;5974.940250597525;5978.701064946736;5989.251343582052;5999.610025348338;4361.738295702268;4427.048184640318;4535;4596.5403459654435;4597.701149425344;4057.655099316532;4106.3840423936645;4110.527289361702;4114.547399786021;4119.773412462283;5725.227094342294;5727.971360143172;5728.198052272685;5730.570207234391;5749.223854779551;5777.826665200051;5818.301803783488;5979.431953964453;6055.333913269536;6056.273247210275;5501.807436739695;5609.27079479665;5676.063449530864;5691.117876729114;5751.798687045934;5160.354955630546;5168;5181.326427564392;5221.712805795718;5226.242194881721;6203.720832562545;6210.472109870711;6211.565190436626;6213.130161777355;6222.097795819672;5764.847030593982;5767.365589785037;5774.3648198697265;5817.7963771267805;5842.328132264844;6002.249718785152;6051.455369016633;6074.301455332693;6106.084087386927;6107.9389206109145;5754.6547207167705;5811.76752929895;5811.883762324645;5813.680247586423;5818.8254352369495;5778.479936805707;5851.561132914963;5898;5904.763809447574;5915.349311575809;5703.657780533182;5706.258186435737;5716.828495145152;5765.827025189251;5858.648481091148;5810.157527158453;5819.15622234782;5826.329972053099;5827.038538641077;5831.7667293307795;5743.224664670216;5795.420457954171;5799.710014499343;5803.9419605803405;5808.448197421239;5686.4029276926985;5730.684812335361;5807.912881306699;5816.680082595498;5896.970515147397;5781;5792.247007888947;5792.304923409218;5793.710314484343;5794.043982742969 04:01:55 INFO - PROCESS | 12617 | |1;ext.html;585.7159277750291;622.2501885228301;633.1009965848494;649.5388274325229;664.8869692152333;34244.82877585612;44090.77954610226;44930.77534612331;45006.7749661252;45126.323105153446;46546.069078618406;46820.297695534595;46966.29550556745;46981.29528057073;47149.0570188596;8445.113263107378;9339.859902101474;9490.383125096847;9498.620055197818;9502.192313653313;8489.915100848973;10923.726906827353;11177.329360238411;11177.385243811586;11468.139889508278;11484.885151148465;11543.422828858565;11559.24864883784;11564.768704625902;11568.59509917153;11175.44122793861;11196.66410007701;11231.438428078563;11234.382108984002;11247.325160490396;11134.49894754738;11201.775964480747;11205.551777928873;11209.327440353523;11222.719432014183;44575;44879.326810097875;44886.77556612196;44906.32640510395;44906.77546612246;3892.8442862285474;3968.0873399118077;4020.4170395292517;4135.834566617332;4519.231730605805;34234.14414639629;34381.48427773586;34716;36541;36649;4804.663673542854;4847.75761211938;4862.78117484714;4901.534354236377;4948.158813001798;397269;397882.9999999971;399310;400495;400895;65979;66298.66850665813;66307;66362;66364.66817665978;39726;40348.19303613911;40435.59564404377;42161;68909;18702.81297187038;18807.435776926715;18833.43499694984;18854.245830166914;18866.811331886507;11816.113791465674;12251;13479.932600336935;14111.929440352733;14305;19761.505962351057;20491.897540512204;20681.89659051695;20704.48238794042;20740.06669699867;7161.1687747055075;7204.9639751800905;7263.709451621981;7339.74310899116;7601.12587052485;1040.7086015915556;1062.9681109566725;1117.8826223246604;1126.566271985275;1174.506707182985;31428.685713143033;31438.842805785825;31874;32213.838930805196;32235;1156.8958793708607;1166;1191.7795207886568;1214.4231490042337;1244.153975296807;16796.832031679773;17279.481615551555;17333.2200050998;17413.390531331344;17418.912905435645;168309;168553;168973;169065;169425.15287423483;62406;63167;63281.68359158175;63291.36708632947;63369.68315158487;1277;1338.2438922009035;1396.6368744126803;1410.8659677330843;1439.820022497188;30875;35038.47442288317;35543.46684799782;35678.464823027156;35704.64295357013;52865.735671321396;53280;53387.46612533825;53481.732591338354;53496.73251633717;405231;405638;406434;406943;407009;30694.539581906734;31659.20851978719;31888.681113189497;33274;33284;51530;51628.22557661713;51843.740781295855;52238.47761522336;52747.736261318445;43211.783941080095;43791.78104109459;44194.86129491348;44240.336394953454;44949.325760114276;43563.12873742571;43870.34194487149;44096;44549.77725111353;45819.54180458286;44005.77997109994;44848.77575612101;44950.55049449595;44984.77507612572;45441.54558454373;36715.81642091773;39109.608903910594;39936.60063399445;40036.39945400763;40652.79673601613;29627.40745185127;30094.84952575223;30361;30816;30881 04:01:55 INFO - PROCESS | 12617 | |2;jquery.html;398.30098177698136;413.70833562338555;426.33278918492556;428.71704674914565;438.39063701454984;14880.404783808648;16603.584910377245;19907.30324438645;20693.586128277424;22209;19043;19414.126364313604;19427.611447771036;19601;19621;5772.5670574706855;5843.386444423317;5984.970075149618;6094;6136.171616831715;4155.729877557949;6130.478909292716;6169.845753856144;6171.166892469504;6175.011998080308;4385.254506733847;4578.931316030262;6535.869282614345;6595.307492713268;6597.175353080865;6150.323464418911;6225.066240063981;6594.373534514213;6598.538102332838;6602.834929126786;5963.5527335450015;5963.940360596382;5974.074018527135;5983.58114931955;6013.939860601382;15052.32264548098;15072.321745521478;15146.242687865562;15220.391184352613;15434.691306173814;2548.1591074945222;2556.923292301234;2748.50526905157;2848.8005839591237;2861.3848022675224;11693.649190524298;12812.551560695425;14470.493532726305;15710.921445392814;16057.197140142945;1987.5130593004662;2053.5174234054984;2061.154926480136;2084.0830034784644;2113.1336152177687;87578;88038.55980720055;89223;91128.54435727779;91972;10893;10994.230403871812;11084.833727494093;11124;11352.205345625893;15059.623509412353;15147;15147.54557363281;15157.166355850313;15171.16558589266;8382.622781974826;8585.871211931826;8686.913130868737;8716.171963663443;8745.868811967826;8558;8593.484390936439;8601.526916019679;8752.343574231958;8789.296856251485;4445.822167113344;4514.074614703978;4547.249703798902;4606.608438282717;4643.326717625925;1746.6506698660319;1919.6256729937807;1925.9711104333446;1956.2370675436591;2053.425040988526;918.6233644205844;987.0820137272262;1001.6043662753171;1020.1634659579074;1083.5882364701363;19894.70157947632;20350.796492035188;20560.177592896416;20709.58580828405;20823;1007.6573964851839;1031.5718976624617;1043.9425831579183;1076.7684947736118;1117.9552817887356;10556.947215264028;11554.711132221762;11621.6513504595;11681.065514758799;11695.941520292345;432615;435496;443871.6873649299;454145;456219;90104.54947725219;94315;94670.52664736632;96160.51919740497;97079.51460242654;992.93545919515;1017.1100287248657;1030.9381437113648;1031.6440827914357;1033.1476531861244;17579.56051098733;18014.72977905306;18437.815621844147;18485.35301264503;18567.16447759881;43303;43585;44153.55846441494;44316.778416107714;44454.3331850029;59430.70284648722;60023;61031;61636.38363616307;62320;8548.401611887108;8953.32850036263;9017.639294428149;9666.275029372966;9703.12671859536;13015.804762928374;13278.734425311633;13331.866681333062;13993.090449120775;14010.439582416997;11722.94138529302;11768.882311177122;11868.584599538975;11909.285442873108;12524.499020039097;12044.21712588679;12157;12195.268283902995;12314.261144331369;12460.937695311466;12359;12402.317872517098;12415.751684966432;12453.688657783629;12460.688482788002;8921.598528066383;9014.368994170345;9025.548722563977;9031.096890310917;9456.527173641152;9825.606975720892;11916.285022898654;12363.814542782045;12408.128401732196;12666.936665316614 04:01:55 INFO - PROCESS | 12617 | |3;mootools.html;1414.9292535373243;1502.4215676964368;1532.2491978930325;1659.4440862311126;1784.8764661841776;8039.477433966788;8814.911850881497;9054.637814487422;9115.817683646323;9486.430814151156;9329.626814927395;9396.671116510923;9406.341556091076;9411.341206115558;9417.43495390277;2572.9871350643266;2599.9350016249555;2601.4536947241104;2604.609308603706;2605.778508826752;2529.456166924111;2555.0546297869814;2562.6668533090674;2563.5898256278906;2564.0897481394118;2518.659980902573;2536.606825941982;2579.3809485723386;2583.250857251395;2584.638150658909;2452.681151450309;2489.054159419427;2500.0499810072056;2508.673872396586;2514.962275565868;2447.131268399728;2453.914113006036;2457.0540341968294;2482.094035676983;2526.759957804006;9096.545172741402;9226.723198304062;9228.676996305165;9328.626854925795;9330.253579713675;5652.152177173416;5830.82507524775;5870.73581688825;5873;5893.58744887858;3609.7834129952025;3650;3682.815859207029;3737.0470530014672;3746.7939263340504;1349.6963183283683;1363.6727185475513;1376.5388594820747;1399.3912647998154;1403.361470530906;13115.344232788419;13192.802107968293;13216.73566528664;13221;13232.470701171942;4775.761211939389;4780.569748722632;4851.345068415719;4974.129527332702;5011.173156429149;10392.948035259775;10405.739856503647;10408.687739367831;10408.895911040943;10472.424016679004;5552.139418390076;5552.139418390156;5591.30108736408;5597.552195824324;5599.692016939108;4419.734815911055;4607.861764147081;4895.510448955147;4895.828645997444;5225.503577160163;4150.792460376969;4188.162367526507;4255.87232383029;4301.483821941324;4325.5674432557125;1234.4691782533416;1239.0645062977394;1249.3503378243263;1268.0679700420185;1280.1998750780763;805.5126648377765;816.9142240064708;817.9550124743196;824.2087595907996;825.3149885594942;6146.385361463907;6376.29860715321;6401.679916004181;6481.351864813481;6597.571157874723;514.5471984653556;514.6037551085643;514.6423235851048;522.1958184396063;530.0194639916124;4293.763842988666;4427.46870375557;4430.490493593214;4536.909261814745;4552.931706024412;11218.831717524243;11511.079113670887;11633.243839150597;11642.476088576035;11654.53381864733;10855;11351.772964540829;11403.315801051964;11554.191206615456;11583.247088939192;8439.535825529532;8647.351448641377;8652.8702069469;8653.567321633893;8658.134186581417;6304.338044505261;6353.745850165941;6390.4248617624635;6473.2232132144445;6575.210974683068;10349.172066234834;10670.253082284165;10688.358698478116;10787.244892857738;10820.134389248999;9875.012498750068;10159.492025398848;10968.780624387628;10984.505697243483;10986.835197471884;7613.885791713239;7776.805579860549;7780.299773020455;7888.447808653339;7993.880091798512;8608.698695545876;8621.87067194005;8644.56777161152;8665.610047548002;8670.26302764272;7599.278068583476;7614.200508946481;7663.846723065619;7820.569868657051;7860.449768516378;7531.359834413917;7575.583342915972;7601.315881570464;7704.499207551491;7786.18245084258;6948.131483564554;7062.894056589258;7068.469864760061;7069.187043490065;7074.292570742885;5382.596305277041;5474.91787623178;5487.368952570506;5529.115341545334;5713.942860571341;5491.588130890279;5528.253685752372;5568.415316391721;5607.5513958882375;5660.7169641518585 04:01:55 INFO - PROCESS | 12617 | |4;prototype.html;350.65285367486194;353.53333599648477;356.06355285598886;357.4174096223156;365.85914422947155;5458.181272809075;5502.889942201158;5520.282363292767;5551.694656793875;5565.359983601887;5180.891465120925;5200.531952124308;5335.146376579748;5396.9190462143;5407.3781515125775;4338.3492476128595;4355.063661312817;4358.8910277243;4365.563443655625;4367.872360619216;4336.913261734763;4337.566243375669;4361.738295702268;4373.5785869592355;4384.276594361942;4143.171365726852;4325.264705000158;4367.868963931087;4381.846635367763;4388;4334.9783251083545;4354.303311470181;4355.281378572532;4359.084592235635;4370.868873933787;3929.8624548140965;3931.857137859873;3945.4279129526344;3945.763254204757;3947.8026098695236;5137.383513978347;5330.040592693314;5338.46615338463;5344.625876188668;5380.946190538123;4980.526849949249;4984.87537811554;4989.501049894981;4999.350084488993;5015.247712843066;5732.426757324234;5734.913976290359;5747.252857128588;5750.607141786324;5753.597248192628;4155.501339839208;4266.658667306608;4284.1364006656895;4306.956930430687;4307;5889.087191485284;5921.259842519685;5956.74908269264;5966.403359664042;5967.940320596826;4389.231884420277;4396.780160991938;4404.911901761947;4408.346869923778;4417.668674849399;4357.7385356878685;4423.734575925455;4455.777211139495;4470.8435204767675;4488.618467430239;4988.625853060962;4996.850094497171;5001.349824522788;5007.599392048627;5008.874778130576;5770.394108618619;5781.653100813965;5787.739551720183;5787.971060144757;5789.681567513827;1465.5896349022291;1471.7056588682306;1501.7221813964452;1503.9699206015819;1536.3624096000258;1069.8127827630133;1076.1444651502075;1092.6066616018227;1096.3367162866512;1102.8786833448314;4862.440819305825;4908.3619129513645;4930.408350997904;4932.827351042696;4941.703497790144;5141.485851414904;5146.0479811235045;5154.226865970059;5160.612954028463;5218.791248350023;4711.952880471152;4871.806407430202;4971.45314015458;4975.776090075955;4979.402471703346;2684.3960108975325;2733.9747594652003;2742.657167854018;2752.587111933226;2752.944941101167;5147.974260128675;5191.909698963184;5199;5231.639773658876;5269.578433725293;5013.373328333958;5107.770150343244;5192.247124166975;5208.322918020709;5222.589900726858;5145.897082058338;5216.843494695166;5235.973820130875;5239.633225674166;5240.03498687992;4995.700257984533;5110.029094472038;5115.335006449138;5116.820911268088;5120.180771076759;4841.007593443406;4928.757415375923;4991.50084991498;5013.473585273639;5065.189569668835;5155.241945559258;5211.869703257449;5218.00857837025;5229.26790249373;5234.555062819703;3924.117073658404;3933.3116704577155;3943.402921816669;3974.682025438016;3978.423128646388;4887.462379138363;4964.304997300309;5068.569171620454;5107.770150343318;5614.494695477427;5601.551875850004;5613.8772245550235;5717.628354156966;5756.510696590837;5779.422057794187;5758.481736643722;5758.942410575841;5764.279465066867;5764.769409223584;5768.336641286306;5570.721463926707;5615.466530679579;5680.488756011979;5716.828495145152;5739.397363276963;5769.480746732815;5777.480026797441;5814.505767009851;5835.416458354131;5842.516930304519;5460.904321758681;5678.119891416837;5680.847129842977;5683.2895888014;5687.829365119053 04:01:55 INFO - PROCESS | 12617 | |5;yui.html;836.2640876029094;841.9368547358949;896.8071864549121;899.2536194958185;957.9185769209616;15127.319270632821;16248.268827902742;16352;16352.509424717262;16357.836421635766;15354;15500.53498395047;16122.677546449064;16793.16034198291;16831.495055148363;729.1286912139982;756.0436048398752;857.8069934264803;886.2422628652474;897.7665806890191;884.6903583745669;884.7699598104507;887.2236792806294;887.5384799904047;887.5695287785419;771.2518856708983;943.7216021273706;944.032366824004;944.8960614332418;945.1682519382933;784.7528028670979;890.8307421589877;918.7703074231442;922.4050487435567;927.4018258223475;673.8753330633848;675.5743881354764;675.9526833121683;676.8172593399804;676.8240257533027;16957.406490772762;17001.23494442753;17009.82990170095;17010.829891701047;17055.744163837553;14932.701345973128;15484.767728484081;15712.29294681742;15725.606859828482;15982.680346393123;13438.731225375535;13539.916806655445;13713.314334283246;13715.519956801463;13768.380422880995;4828.179209534387;4866.416030076413;4895.730734809584;4927.211646136601;4952.14706916272;85771.57114214389;87369.12630873737;87957;90736;92545.53727231389;12770.361481926053;13121.803172952414;13141.080124391392;13361.732765344834;13395;25070;25180.496390072098;25190.874045629655;25404;25417.745822541907;9783.510824458748;9818.75453113678;9891.10980011788;9899;10146.239032072624;5160.509751573594;5174.120399532088;5198.246254293182;5255.789768409298;5312.973435132877;3747.3442147624055;3859.4403811447746;3888.9611103889165;3904.04350934023;3904.277708623857;608.9147519347295;611.8929187392189;612.9570930034944;624.4598422364622;630.3224034163201;631.3907079668143;713.7894321175265;716.9103862017248;724.9202587715347;740.5223630758131;9032.548372581476;9621.615135394646;9713.320067595341;9875.40747555149;9908.711867457185;630.124127462827;655.2170156662743;704.5772536478072;704.6580773042616;719.6365835253164;4712.577467026834;4976.985040276139;4994.704996196506;5004;5020.246962955586;125392;128469;131934;135274;139815;62725.372746272864;64777;65932.34067659262;66492;110960.4451977735;81905.59047204845;98340.50829745806;102750.48624756829;103929.48035259775;105584.47207763912;9445.669401570913;9553.235741140554;9593.856092158483;9605.663801766905;9689.70930872075;52800;53566;54340.18489722578;54951.72524137354;56039.71980140073;71112;73031.26968730458;76058.61970690111;77510.22489775256;78739.60630196812;12968.286744229157;13927.651808704863;14344.067635603653;14776.63058423548;14907.552773416815;13716.519921802688;16331.183440827674;16768.664626707643;16826.326946921985;17588.57915457104;13298.534551290659;13487;14005.369758361103;14028.158310501403;14037.789433158307;13659.590212293648;13722.588322350346;13890.166590004632;14122.518208121484;14125.505607303696;13229.338533073116;13800;13978.23119728384;14013.089149205269;14100.435982560582;12071.912294346947;12402.13185077036;13021.739565208833;13105.14816536922;13179;10557.73605659834;11287.43562821872;11703.356315402394;11867.287962722265;12147.696307592381 04:01:55 INFO - PROCESS | 12617 | __end_tp_report 04:01:55 INFO - PROCESS | 12617 | __start_cc_report 04:01:55 INFO - PROCESS | 12617 | _x_x_mozilla_cycle_collect,1096 04:01:55 INFO - PROCESS | 12617 | __end_cc_report 04:01:55 INFO - PROCESS | 12617 | __startTimestamp1477566115947__endTimestamp 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | ------- Summary: start ------- 04:01:55 INFO - PROCESS | 12617 | Number of tests: 6 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#0] dojo.html Cycles:165 Average:5527.09 Median:5754.65 stddev:627.81 (10.9%) stddev-sans-first:600.33 04:01:55 INFO - PROCESS | 12617 | Values: 3106.5 3149.0 3153.7 3171.8 3191.8 5462.5 5566.9 5573.5 5574.1 5577.0 5568.1 5654.4 5656.7 5664.1 5685.5 5111.8 5116.1 5149.7 5185.8 5194.4 4954.3 5138.9 5203.6 5206.7 5211.6 4940.2 5053.8 5059.3 5106.4 5127.6 5066.0 5089.5 5097.0 5150.9 5176.0 4972.8 5041.2 5082.4 5087.4 5110.2 5322.3 5355.3 5411.7 5474.4 5477.4 5873.0 5904.7 5914.2 5924.0 5928.3 5944.0 5963.1 5965.3 5967.6 5970.6 5317.4 5368.4 5504.6 5556.1 5586.0 6188.8 6193.5 6194.3 6202.9 6204.5 5923.1 5924.4 5930.3 5951.7 5971.7 5710.9 5834.2 5857.0 5928.1 6033.9 5941.6 5955.4 5968.1 6007.9 6019.6 5967.5 5974.9 5978.7 5989.3 5999.6 4361.7 4427.0 4535.0 4596.5 4597.7 4057.7 4106.4 4110.5 4114.5 4119.8 5725.2 5728.0 5728.2 5730.6 5749.2 5777.8 5818.3 5979.4 6055.3 6056.3 5501.8 5609.3 5676.1 5691.1 5751.8 5160.4 5168.0 5181.3 5221.7 5226.2 6203.7 6210.5 6211.6 6213.1 6222.1 5764.8 5767.4 5774.4 5817.8 5842.3 6002.2 6051.5 6074.3 6106.1 6107.9 5754.7 5811.8 5811.9 5813.7 5818.8 5778.5 5851.6 5898.0 5904.8 5915.3 5703.7 5706.3 5716.8 5765.8 5858.6 5810.2 5819.2 5826.3 5827.0 5831.8 5743.2 5795.4 5799.7 5803.9 5808.4 5686.4 5730.7 5807.9 5816.7 5897.0 5781.0 5792.2 5792.3 5793.7 5794.0 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#1] ext.html Cycles:180 Average:51963.26 Median:397576.00 stddev:90428.08 (22.7%) stddev-sans-first:90599.47 04:01:55 INFO - PROCESS | 12617 | Values: 585.7 622.3 633.1 649.5 664.9 34244.8 44090.8 44930.8 45006.8 45126.3 46546.1 46820.3 46966.3 46981.3 47149.1 8445.1 9339.9 9490.4 9498.6 9502.2 8489.9 10923.7 11177.3 11177.4 11468.1 11484.9 11543.4 11559.2 11564.8 11568.6 11175.4 11196.7 11231.4 11234.4 11247.3 11134.5 11201.8 11205.6 11209.3 11222.7 44575.0 44879.3 44886.8 44906.3 44906.8 3892.8 3968.1 4020.4 4135.8 4519.2 34234.1 34381.5 34716.0 36541.0 36649.0 4804.7 4847.8 4862.8 4901.5 4948.2 397269.0 397883.0 399310.0 400495.0 400895.0 65979.0 66298.7 66307.0 66362.0 66364.7 39726.0 40348.2 40435.6 42161.0 68909.0 18702.8 18807.4 18833.4 18854.2 18866.8 11816.1 12251.0 13479.9 14111.9 14305.0 19761.5 20491.9 20681.9 20704.5 20740.1 7161.2 7205.0 7263.7 7339.7 7601.1 1040.7 1063.0 1117.9 1126.6 1174.5 31428.7 31438.8 31874.0 32213.8 32235.0 1156.9 1166.0 1191.8 1214.4 1244.2 16796.8 17279.5 17333.2 17413.4 17418.9 168309.0 168553.0 168973.0 169065.0 169425.2 62406.0 63167.0 63281.7 63291.4 63369.7 1277.0 1338.2 1396.6 1410.9 1439.8 30875.0 35038.5 35543.5 35678.5 35704.6 52865.7 53280.0 53387.5 53481.7 53496.7 405231.0 405638.0 406434.0 406943.0 407009.0 30694.5 31659.2 31888.7 33274.0 33284.0 51530.0 51628.2 51843.7 52238.5 52747.7 43211.8 43791.8 44194.9 44240.3 44949.3 43563.1 43870.3 44096.0 44549.8 45819.5 44005.8 44848.8 44950.6 44984.8 45441.5 36715.8 39109.6 39936.6 40036.4 40652.8 29627.4 30094.8 30361.0 30816.0 30881.0 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#2] jquery.html Cycles:180 Average:28318.57 Median:12378.58 stddev:73820.14 (596.4%) stddev-sans-first:73997.46 04:01:55 INFO - PROCESS | 12617 | Values: 398.3 413.7 426.3 428.7 438.4 14880.4 16603.6 19907.3 20693.6 22209.0 19043.0 19414.1 19427.6 19601.0 19621.0 5772.6 5843.4 5985.0 6094.0 6136.2 4155.7 6130.5 6169.8 6171.2 6175.0 4385.3 4578.9 6535.9 6595.3 6597.2 6150.3 6225.1 6594.4 6598.5 6602.8 5963.6 5963.9 5974.1 5983.6 6013.9 15052.3 15072.3 15146.2 15220.4 15434.7 2548.2 2556.9 2748.5 2848.8 2861.4 11693.6 12812.6 14470.5 15710.9 16057.2 1987.5 2053.5 2061.2 2084.1 2113.1 87578.0 88038.6 89223.0 91128.5 91972.0 10893.0 10994.2 11084.8 11124.0 11352.2 15059.6 15147.0 15147.5 15157.2 15171.2 8382.6 8585.9 8686.9 8716.2 8745.9 8558.0 8593.5 8601.5 8752.3 8789.3 4445.8 4514.1 4547.2 4606.6 4643.3 1746.7 1919.6 1926.0 1956.2 2053.4 918.6 987.1 1001.6 1020.2 1083.6 19894.7 20350.8 20560.2 20709.6 20823.0 1007.7 1031.6 1043.9 1076.8 1118.0 10556.9 11554.7 11621.7 11681.1 11695.9 432615.0 435496.0 443871.7 454145.0 456219.0 90104.5 94315.0 94670.5 96160.5 97079.5 992.9 1017.1 1030.9 1031.6 1033.1 17579.6 18014.7 18437.8 18485.4 18567.2 43303.0 43585.0 44153.6 44316.8 44454.3 59430.7 60023.0 61031.0 61636.4 62320.0 8548.4 8953.3 9017.6 9666.3 9703.1 13015.8 13278.7 13331.9 13993.1 14010.4 11722.9 11768.9 11868.6 11909.3 12524.5 12044.2 12157.0 12195.3 12314.3 12460.9 12359.0 12402.3 12415.8 12453.7 12460.7 8921.6 9014.4 9025.5 9031.1 9456.5 9825.6 11916.3 12363.8 12408.1 12666.9 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#3] mootools.html Cycles:180 Average:6065.37 Median:4934.98 stddev:3437.73 (69.7%) stddev-sans-first:3429.61 04:01:55 INFO - PROCESS | 12617 | Values: 1414.9 1502.4 1532.2 1659.4 1784.9 8039.5 8814.9 9054.6 9115.8 9486.4 9329.6 9396.7 9406.3 9411.3 9417.4 2573.0 2599.9 2601.5 2604.6 2605.8 2529.5 2555.1 2562.7 2563.6 2564.1 2518.7 2536.6 2579.4 2583.3 2584.6 2452.7 2489.1 2500.0 2508.7 2515.0 2447.1 2453.9 2457.1 2482.1 2526.8 9096.5 9226.7 9228.7 9328.6 9330.3 5652.2 5830.8 5870.7 5873.0 5893.6 3609.8 3650.0 3682.8 3737.0 3746.8 1349.7 1363.7 1376.5 1399.4 1403.4 13115.3 13192.8 13216.7 13221.0 13232.5 4775.8 4780.6 4851.3 4974.1 5011.2 10392.9 10405.7 10408.7 10408.9 10472.4 5552.1 5552.1 5591.3 5597.6 5599.7 4419.7 4607.9 4895.5 4895.8 5225.5 4150.8 4188.2 4255.9 4301.5 4325.6 1234.5 1239.1 1249.4 1268.1 1280.2 805.5 816.9 818.0 824.2 825.3 6146.4 6376.3 6401.7 6481.4 6597.6 514.5 514.6 514.6 522.2 530.0 4293.8 4427.5 4430.5 4536.9 4552.9 11218.8 11511.1 11633.2 11642.5 11654.5 10855.0 11351.8 11403.3 11554.2 11583.2 8439.5 8647.4 8652.9 8653.6 8658.1 6304.3 6353.7 6390.4 6473.2 6575.2 10349.2 10670.3 10688.4 10787.2 10820.1 9875.0 10159.5 10968.8 10984.5 10986.8 7613.9 7776.8 7780.3 7888.4 7993.9 8608.7 8621.9 8644.6 8665.6 8670.3 7599.3 7614.2 7663.8 7820.6 7860.4 7531.4 7575.6 7601.3 7704.5 7786.2 6948.1 7062.9 7068.5 7069.2 7074.3 5382.6 5474.9 5487.4 5529.1 5713.9 5491.6 5528.3 5568.4 5607.6 5660.7 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#4] prototype.html Cycles:180 Average:4643.75 Median:5013.42 stddev:1297.83 (25.9%) stddev-sans-first:1260.84 04:01:55 INFO - PROCESS | 12617 | Values: 350.7 353.5 356.1 357.4 365.9 5458.2 5502.9 5520.3 5551.7 5565.4 5180.9 5200.5 5335.1 5396.9 5407.4 4338.3 4355.1 4358.9 4365.6 4367.9 4336.9 4337.6 4361.7 4373.6 4384.3 4143.2 4325.3 4367.9 4381.8 4388.0 4335.0 4354.3 4355.3 4359.1 4370.9 3929.9 3931.9 3945.4 3945.8 3947.8 5137.4 5330.0 5338.5 5344.6 5380.9 4980.5 4984.9 4989.5 4999.4 5015.2 5732.4 5734.9 5747.3 5750.6 5753.6 4155.5 4266.7 4284.1 4307.0 4307.0 5889.1 5921.3 5956.7 5966.4 5967.9 4389.2 4396.8 4404.9 4408.3 4417.7 4357.7 4423.7 4455.8 4470.8 4488.6 4988.6 4996.9 5001.3 5007.6 5008.9 5770.4 5781.7 5787.7 5788.0 5789.7 1465.6 1471.7 1501.7 1504.0 1536.4 1069.8 1076.1 1092.6 1096.3 1102.9 4862.4 4908.4 4930.4 4932.8 4941.7 5141.5 5146.0 5154.2 5160.6 5218.8 4712.0 4871.8 4971.5 4975.8 4979.4 2684.4 2734.0 2742.7 2752.6 2752.9 5148.0 5191.9 5199.0 5231.6 5269.6 5013.4 5107.8 5192.2 5208.3 5222.6 5145.9 5216.8 5236.0 5239.6 5240.0 4995.7 5110.0 5115.3 5116.8 5120.2 4841.0 4928.8 4991.5 5013.5 5065.2 5155.2 5211.9 5218.0 5229.3 5234.6 3924.1 3933.3 3943.4 3974.7 3978.4 4887.5 4964.3 5068.6 5107.8 5614.5 5601.6 5613.9 5717.6 5756.5 5779.4 5758.5 5758.9 5764.3 5764.8 5768.3 5570.7 5615.5 5680.5 5716.8 5739.4 5769.5 5777.5 5814.5 5835.4 5842.5 5460.9 5678.1 5680.8 5683.3 5687.8 04:01:55 INFO - PROCESS | 12617 | 04:01:55 INFO - PROCESS | 12617 | [#5] yui.html Cycles:180 Average:21986.14 Median:4964.57 stddev:31716.95 (638.9%) stddev-sans-first:31766.16 04:01:55 INFO - PROCESS | 12617 | Values: 836.3 841.9 896.8 899.3 957.9 15127.3 16248.3 16352.0 16352.5 16357.8 15354.0 15500.5 16122.7 16793.2 16831.5 729.1 756.0 857.8 886.2 897.8 884.7 884.8 887.2 887.5 887.6 771.3 943.7 944.0 944.9 945.2 784.8 890.8 918.8 922.4 927.4 673.9 675.6 676.0 676.8 676.8 16957.4 17001.2 17009.8 17010.8 17055.7 14932.7 15484.8 15712.3 15725.6 15982.7 13438.7 13539.9 13713.3 13715.5 13768.4 4828.2 4866.4 4895.7 4927.2 4952.1 85771.6 87369.1 87957.0 90736.0 92545.5 12770.4 13121.8 13141.1 13361.7 13395.0 25070.0 25180.5 25190.9 25404.0 25417.7 9783.5 9818.8 9891.1 9899.0 10146.2 5160.5 5174.1 5198.2 5255.8 5313.0 3747.3 3859.4 3889.0 3904.0 3904.3 608.9 611.9 613.0 624.5 630.3 631.4 713.8 716.9 724.9 740.5 9032.5 9621.6 9713.3 9875.4 9908.7 630.1 655.2 704.6 704.7 719.6 4712.6 4977.0 4994.7 5004.0 5020.2 125392.0 128469.0 131934.0 135274.0 139815.0 62725.4 64777.0 65932.3 66492.0 110960.4 81905.6 98340.5 102750.5 103929.5 105584.5 9445.7 9553.2 9593.9 9605.7 9689.7 52800.0 53566.0 54340.2 54951.7 56039.7 71112.0 73031.3 76058.6 77510.2 78739.6 12968.3 13927.7 14344.1 14776.6 14907.6 13716.5 16331.2 16768.7 16826.3 17588.6 13298.5 13487.0 14005.4 14028.2 14037.8 13659.6 13722.6 13890.2 14122.5 14125.5 13229.3 13800.0 13978.2 14013.1 14100.4 12071.9 12402.1 13021.7 13105.1 13179.0 10557.7 11287.4 11703.4 11867.3 12147.7 04:01:55 INFO - PROCESS | 12617 | -------- Summary: end -------- 04:01:55 INFO - PROCESS | 12617 | 04:01:56 INFO - TEST-INFO | 12617: exit 0 04:01:56 INFO - TEST-OK | dromaeo_css | took 1113839ms 04:01:56 INFO - TEST-START | kraken 04:01:56 INFO - operating with platform_type : linux_ 04:01:56 INFO - Initialising browser for kraken test... 04:01:56 INFO - TEST-INFO | started process 12937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpICnzSZ/profile http://localhost:40658/getInfo.html) 04:02:00 INFO - PROCESS | 12937 | __metrics Screen width/height:1600/1200 04:02:00 INFO - PROCESS | 12937 | colorDepth:24 04:02:00 INFO - PROCESS | 12937 | Browser inner width/height: 1024/697 04:02:00 INFO - PROCESS | 12937 | __metrics 04:02:01 INFO - PROCESS | 12937 | 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" 04:02:01 INFO - PROCESS | 12937 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:02:02 INFO - PROCESS | 12937 | console.error: PushService: 04:02:02 INFO - PROCESS | 12937 | stateChangeProcessEnqueue: Error transitioning state 04:02:02 INFO - PROCESS | 12937 | UnknownError 04:02:02 INFO - TEST-INFO | 12937: exit 0 04:02:02 INFO - Browser initialized. 04:02:02 INFO - Running cycle 1/1 for kraken test... 04:02:02 INFO - TEST-INFO | started process 13028 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpICnzSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:14 INFO - PROCESS | 13028 | RSS: Main: 144826368 04:02:14 INFO - PROCESS | 13028 | 04:02:16 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40658/tests/kraken/driver.html?testName=audio-beat-detection) 04:02:17 INFO - PROCESS | 13028 | RSS: Main: 186417152 04:02:17 INFO - PROCESS | 13028 | 04:02:20 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40658/tests/kraken/driver.html?testName=audio-dft) 04:02:20 INFO - PROCESS | 13028 | RSS: Main: 175882240 04:02:20 INFO - PROCESS | 13028 | 04:02:23 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40658/tests/kraken/driver.html?testName=audio-fft) 04:02:24 INFO - PROCESS | 13028 | RSS: Main: 267481088 04:02:24 INFO - PROCESS | 13028 | 04:02:26 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40658/tests/kraken/driver.html?testName=audio-oscillator) 04:02:26 INFO - PROCESS | 13028 | RSS: Main: 173252608 04:02:26 INFO - PROCESS | 13028 | 04:02:28 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40658/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:02:29 INFO - PROCESS | 13028 | RSS: Main: 174821376 04:02:29 INFO - PROCESS | 13028 | 04:02:32 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40658/tests/kraken/driver.html?testName=imaging-darkroom) 04:02:33 INFO - PROCESS | 13028 | RSS: Main: 210849792 04:02:33 INFO - PROCESS | 13028 | 04:02:36 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40658/tests/kraken/driver.html?testName=imaging-desaturate) 04:02:37 INFO - PROCESS | 13028 | RSS: Main: 209494016 04:02:37 INFO - PROCESS | 13028 | 04:02:39 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40658/tests/kraken/driver.html?testName=json-parse-financial) 04:02:40 INFO - PROCESS | 13028 | RSS: Main: 210206720 04:02:40 INFO - PROCESS | 13028 | 04:02:41 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40658/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:02:42 INFO - PROCESS | 13028 | RSS: Main: 171786240 04:02:42 INFO - PROCESS | 13028 | 04:02:44 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:02:44 INFO - PROCESS | 13028 | RSS: Main: 178962432 04:02:44 INFO - PROCESS | 13028 | 04:02:46 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:02:47 INFO - PROCESS | 13028 | RSS: Main: 188456960 04:02:47 INFO - PROCESS | 13028 | 04:02:49 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:02:50 INFO - PROCESS | 13028 | RSS: Main: 189591552 04:02:50 INFO - PROCESS | 13028 | 04:02:53 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:02:53 INFO - PROCESS | 13028 | RSS: Main: 192622592 04:02:53 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | Cycle 1(1): loaded http://localhost:40658/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40658/tests/kraken/driver.html?testName=ai-astar) 04:02:55 INFO - PROCESS | 13028 | RSS: Main: 243429376 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | __start_tp_report 04:02:55 INFO - PROCESS | 13028 | _x_x_mozilla_page_load 04:02:55 INFO - PROCESS | 13028 | _x_x_mozilla_page_load_details 04:02:55 INFO - PROCESS | 13028 | |i|pagename|runs| 04:02:55 INFO - PROCESS | 13028 | |0;ai-astar;110;113;111;110;113;111;111;110;110;110 04:02:55 INFO - PROCESS | 13028 | |1;audio-beat-detection;144;116;124;162;117;123;116;115;144;115 04:02:55 INFO - PROCESS | 13028 | |2;audio-dft;161;160;159;160;159;159;160;160;162;160 04:02:55 INFO - PROCESS | 13028 | |3;audio-fft;75;75;75;101;75;76;76;75;98;74 04:02:55 INFO - PROCESS | 13028 | |4;audio-oscillator;82;82;110;82;83;81;99;82;82;104 04:02:55 INFO - PROCESS | 13028 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;117 04:02:55 INFO - PROCESS | 13028 | |6;imaging-darkroom;146;146;146;146;147;145;145;146;146;146 04:02:55 INFO - PROCESS | 13028 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;96;94 04:02:55 INFO - PROCESS | 13028 | |8;json-parse-financial;51;49;48;50;49;50;48;49;49;53 04:02:55 INFO - PROCESS | 13028 | |9;json-stringify-tinderbox;59;59;59;60;60;59;59;59;59;59 04:02:55 INFO - PROCESS | 13028 | |10;stanford-crypto-aes;76;74;73;78;73;73;73;73;81;74 04:02:55 INFO - PROCESS | 13028 | |11;stanford-crypto-ccm;123;123;123;120;119;117;118;119;145;140 04:02:55 INFO - PROCESS | 13028 | |12;stanford-crypto-pbkdf2;153;152;152;154;153;154;150;152;152;156 04:02:55 INFO - PROCESS | 13028 | |13;stanford-crypto-sha256-iterative;61;61;61;61;62;61;61;60;64;60 04:02:55 INFO - PROCESS | 13028 | __end_tp_report 04:02:55 INFO - PROCESS | 13028 | __start_cc_report 04:02:55 INFO - PROCESS | 13028 | _x_x_mozilla_cycle_collect,1595 04:02:55 INFO - PROCESS | 13028 | __end_cc_report 04:02:55 INFO - PROCESS | 13028 | __startTimestamp1477566175556__endTimestamp 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | ------- Summary: start ------- 04:02:55 INFO - PROCESS | 13028 | Number of tests: 14 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.00 stddev:1.20 (1.1%) stddev-sans-first:1.22 04:02:55 INFO - PROCESS | 13028 | Values: 110.0 113.0 111.0 110.0 113.0 111.0 111.0 110.0 110.0 110.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#1] audio-beat-detection Cycles:10 Average:127.60 Median:123.50 stddev:16.51 (13.4%) stddev-sans-first:16.41 04:02:55 INFO - PROCESS | 13028 | Values: 144.0 116.0 124.0 162.0 117.0 123.0 116.0 115.0 144.0 115.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 04:02:55 INFO - PROCESS | 13028 | Values: 161.0 160.0 159.0 160.0 159.0 159.0 160.0 160.0 162.0 160.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.00 stddev:10.32 (13.8%) stddev-sans-first:10.78 04:02:55 INFO - PROCESS | 13028 | Values: 75.0 75.0 75.0 101.0 75.0 76.0 76.0 75.0 98.0 74.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:82.00 stddev:11.11 (13.5%) stddev-sans-first:11.51 04:02:55 INFO - PROCESS | 13028 | Values: 82.0 82.0 110.0 82.0 83.0 81.0 99.0 82.0 82.0 104.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:02:55 INFO - PROCESS | 13028 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 04:02:55 INFO - PROCESS | 13028 | Values: 146.0 146.0 146.0 146.0 147.0 145.0 145.0 146.0 146.0 146.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:02:55 INFO - PROCESS | 13028 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 96.0 94.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:1.51 (3.0%) stddev-sans-first:1.51 04:02:55 INFO - PROCESS | 13028 | Values: 51.0 49.0 48.0 50.0 49.0 50.0 48.0 49.0 49.0 53.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 04:02:55 INFO - PROCESS | 13028 | Values: 59.0 59.0 59.0 60.0 60.0 59.0 59.0 59.0 59.0 59.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.00 stddev:2.74 (3.7%) stddev-sans-first:2.87 04:02:55 INFO - PROCESS | 13028 | Values: 76.0 74.0 73.0 78.0 73.0 73.0 73.0 73.0 81.0 74.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:123.00 stddev:9.70 (7.9%) stddev-sans-first:10.26 04:02:55 INFO - PROCESS | 13028 | Values: 123.0 123.0 123.0 120.0 119.0 117.0 118.0 119.0 145.0 140.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:1.62 (1.1%) stddev-sans-first:1.72 04:02:55 INFO - PROCESS | 13028 | Values: 153.0 152.0 152.0 154.0 153.0 154.0 150.0 152.0 152.0 156.0 04:02:55 INFO - PROCESS | 13028 | 04:02:55 INFO - PROCESS | 13028 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.14 (1.9%) stddev-sans-first:1.20 04:02:55 INFO - PROCESS | 13028 | Values: 61.0 61.0 61.0 61.0 62.0 61.0 61.0 60.0 64.0 60.0 04:02:55 INFO - PROCESS | 13028 | -------- Summary: end -------- 04:02:55 INFO - PROCESS | 13028 | 04:02:56 INFO - TEST-INFO | 13028: exit 0 04:02:56 INFO - TEST-OK | kraken | took 59887ms 04:02:56 INFO - SUITE-END | took 1173s 04:02:56 INFO - Completed test suite (00:19:33) 04:02:56 INFO - javascript benchmark 04:02:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.549550315204, 3148.9842550787243, 3153.7477001839857, 3171.7621178411623, 3191.840407979602, 5462.4810642988905, 5566.888662226753, 5573.47052030057, 5574.136008918619, 5577.0, 5568.053430916743, 5654.434556544344, 5656.660600363981, 5664.0937450008005, 5685.488306052454, 5111.795528178868, 5116.104681680691, 5149.742512874342, 5185.818496352609, 5194.428612852583, 4954.281629163787, 5138.8715282118055, 5203.635745497817, 5206.661566998152, 5211.609129315298, 4940.2342636891335, 5053.797848086072, 5059.266406371074, 5106.412762532319, 5127.564157046656, 5066.0, 5089.541941225289, 5096.974515127437, 5150.871228219287, 5176.0, 4972.825951091694, 5041.218611115246, 5082.364704411949, 5087.440381558025, 5110.182370820651, 5322.2548843161985, 5355.250264962909, 5411.702356370398, 5474.42518535556, 5477.370102438231, 5872.970635146797, 5904.675242861641, 5914.172015917775, 5923.992921203406, 5928.28860536734, 5944.048952167633, 5963.13534537494, 5965.343812180657, 5967.6419414835245, 5970.641761494324, 5317.388500322435, 5368.355797304349, 5504.614676972654, 5556.138798486241, 5585.972070139624, 6188.81433556994, 6193.473554747807, 6194.318624951252, 6202.875942481125, 6204.472619827366, 5923.052311630118, 5924.437178468039, 5930.347661757203, 5951.702414879325, 5971.701414929236, 5710.914336284959, 5834.212381328466, 5857.0, 5928.140419639129, 6033.879322413527, 5941.613795103304, 5955.37468565803, 5968.074948383008, 6007.909881351783, 6019.608725432833, 5967.522598192134, 5974.940250597525, 5978.701064946736, 5989.251343582052, 5999.610025348338, 4361.738295702268, 4427.048184640318, 4535.0, 4596.5403459654435, 4597.701149425344, 4057.655099316532, 4106.3840423936645, 4110.527289361702, 4114.547399786021, 4119.773412462283, 5725.227094342294, 5727.971360143172, 5728.198052272685, 5730.570207234391, 5749.223854779551, 5777.826665200051, 5818.301803783488, 5979.431953964453, 6055.333913269536, 6056.273247210275, 5501.807436739695, 5609.27079479665, 5676.063449530864, 5691.117876729114, 5751.798687045934, 5160.354955630546, 5168.0, 5181.326427564392, 5221.712805795718, 5226.242194881721, 6203.720832562545, 6210.472109870711, 6211.565190436626, 6213.130161777355, 6222.097795819672, 5764.847030593982, 5767.365589785037, 5774.3648198697265, 5817.7963771267805, 5842.328132264844, 6002.249718785152, 6051.455369016633, 6074.301455332693, 6106.084087386927, 6107.9389206109145, 5754.6547207167705, 5811.76752929895, 5811.883762324645, 5813.680247586423, 5818.8254352369495, 5778.479936805707, 5851.561132914963, 5898.0, 5904.763809447574, 5915.349311575809, 5703.657780533182, 5706.258186435737, 5716.828495145152, 5765.827025189251, 5858.648481091148, 5810.157527158453, 5819.15622234782, 5826.329972053099, 5827.038538641077, 5831.7667293307795, 5743.224664670216, 5795.420457954171, 5799.710014499343, 5803.9419605803405, 5808.448197421239, 5686.4029276926985, 5730.684812335361, 5807.912881306699, 5816.680082595498, 5896.970515147397, 5781.0, 5792.247007888947, 5792.304923409218, 5793.710314484343, 5794.043982742969], "value": 5484.19276471387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.7159277750291, 622.2501885228301, 633.1009965848494, 649.5388274325229, 664.8869692152333, 34244.82877585612, 44090.77954610226, 44930.77534612331, 45006.7749661252, 45126.323105153446, 46546.069078618406, 46820.297695534595, 46966.29550556745, 46981.29528057073, 47149.0570188596, 8445.113263107378, 9339.859902101474, 9490.383125096847, 9498.620055197818, 9502.192313653313, 8489.915100848973, 10923.726906827353, 11177.329360238411, 11177.385243811586, 11468.139889508278, 11484.885151148465, 11543.422828858565, 11559.24864883784, 11564.768704625902, 11568.59509917153, 11175.44122793861, 11196.66410007701, 11231.438428078563, 11234.382108984002, 11247.325160490396, 11134.49894754738, 11201.775964480747, 11205.551777928873, 11209.327440353523, 11222.719432014183, 44575.0, 44879.326810097875, 44886.77556612196, 44906.32640510395, 44906.77546612246, 3892.8442862285474, 3968.0873399118077, 4020.4170395292517, 4135.834566617332, 4519.231730605805, 34234.14414639629, 34381.48427773586, 34716.0, 36541.0, 36649.0, 4804.663673542854, 4847.75761211938, 4862.78117484714, 4901.534354236377, 4948.158813001798, 397269.0, 397882.9999999971, 399310.0, 400495.0, 400895.0, 65979.0, 66298.66850665813, 66307.0, 66362.0, 66364.66817665978, 39726.0, 40348.19303613911, 40435.59564404377, 42161.0, 68909.0, 18702.81297187038, 18807.435776926715, 18833.43499694984, 18854.245830166914, 18866.811331886507, 11816.113791465674, 12251.0, 13479.932600336935, 14111.929440352733, 14305.0, 19761.505962351057, 20491.897540512204, 20681.89659051695, 20704.48238794042, 20740.06669699867, 7161.1687747055075, 7204.9639751800905, 7263.709451621981, 7339.74310899116, 7601.12587052485, 1040.7086015915556, 1062.9681109566725, 1117.8826223246604, 1126.566271985275, 1174.506707182985, 31428.685713143033, 31438.842805785825, 31874.0, 32213.838930805196, 32235.0, 1156.8958793708607, 1166.0, 1191.7795207886568, 1214.4231490042337, 1244.153975296807, 16796.832031679773, 17279.481615551555, 17333.2200050998, 17413.390531331344, 17418.912905435645, 168309.0, 168553.0, 168973.0, 169065.0, 169425.15287423483, 62406.0, 63167.0, 63281.68359158175, 63291.36708632947, 63369.68315158487, 1277.0, 1338.2438922009035, 1396.6368744126803, 1410.8659677330843, 1439.820022497188, 30875.0, 35038.47442288317, 35543.46684799782, 35678.464823027156, 35704.64295357013, 52865.735671321396, 53280.0, 53387.46612533825, 53481.732591338354, 53496.73251633717, 405231.0, 405638.0, 406434.0, 406943.0, 407009.0, 30694.539581906734, 31659.20851978719, 31888.681113189497, 33274.0, 33284.0, 51530.0, 51628.22557661713, 51843.740781295855, 52238.47761522336, 52747.736261318445, 43211.783941080095, 43791.78104109459, 44194.86129491348, 44240.336394953454, 44949.325760114276, 43563.12873742571, 43870.34194487149, 44096.0, 44549.77725111353, 45819.54180458286, 44005.77997109994, 44848.77575612101, 44950.55049449595, 44984.77507612572, 45441.54558454373, 36715.81642091773, 39109.608903910594, 39936.60063399445, 40036.39945400763, 40652.79673601613, 29627.40745185127, 30094.84952575223, 30361.0, 30816.0, 30881.0], "value": 20945.90343880669, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.30098177698136, 413.70833562338555, 426.33278918492556, 428.71704674914565, 438.39063701454984, 14880.404783808648, 16603.584910377245, 19907.30324438645, 20693.586128277424, 22209.0, 19043.0, 19414.126364313604, 19427.611447771036, 19601.0, 19621.0, 5772.5670574706855, 5843.386444423317, 5984.970075149618, 6094.0, 6136.171616831715, 4155.729877557949, 6130.478909292716, 6169.845753856144, 6171.166892469504, 6175.011998080308, 4385.254506733847, 4578.931316030262, 6535.869282614345, 6595.307492713268, 6597.175353080865, 6150.323464418911, 6225.066240063981, 6594.373534514213, 6598.538102332838, 6602.834929126786, 5963.5527335450015, 5963.940360596382, 5974.074018527135, 5983.58114931955, 6013.939860601382, 15052.32264548098, 15072.321745521478, 15146.242687865562, 15220.391184352613, 15434.691306173814, 2548.1591074945222, 2556.923292301234, 2748.50526905157, 2848.8005839591237, 2861.3848022675224, 11693.649190524298, 12812.551560695425, 14470.493532726305, 15710.921445392814, 16057.197140142945, 1987.5130593004662, 2053.5174234054984, 2061.154926480136, 2084.0830034784644, 2113.1336152177687, 87578.0, 88038.55980720055, 89223.0, 91128.54435727779, 91972.0, 10893.0, 10994.230403871812, 11084.833727494093, 11124.0, 11352.205345625893, 15059.623509412353, 15147.0, 15147.54557363281, 15157.166355850313, 15171.16558589266, 8382.622781974826, 8585.871211931826, 8686.913130868737, 8716.171963663443, 8745.868811967826, 8558.0, 8593.484390936439, 8601.526916019679, 8752.343574231958, 8789.296856251485, 4445.822167113344, 4514.074614703978, 4547.249703798902, 4606.608438282717, 4643.326717625925, 1746.6506698660319, 1919.6256729937807, 1925.9711104333446, 1956.2370675436591, 2053.425040988526, 918.6233644205844, 987.0820137272262, 1001.6043662753171, 1020.1634659579074, 1083.5882364701363, 19894.70157947632, 20350.796492035188, 20560.177592896416, 20709.58580828405, 20823.0, 1007.6573964851839, 1031.5718976624617, 1043.9425831579183, 1076.7684947736118, 1117.9552817887356, 10556.947215264028, 11554.711132221762, 11621.6513504595, 11681.065514758799, 11695.941520292345, 432615.0, 435496.0, 443871.6873649299, 454145.0, 456219.0, 90104.54947725219, 94315.0, 94670.52664736632, 96160.51919740497, 97079.51460242654, 992.93545919515, 1017.1100287248657, 1030.9381437113648, 1031.6440827914357, 1033.1476531861244, 17579.56051098733, 18014.72977905306, 18437.815621844147, 18485.35301264503, 18567.16447759881, 43303.0, 43585.0, 44153.55846441494, 44316.778416107714, 44454.3331850029, 59430.70284648722, 60023.0, 61031.0, 61636.38363616307, 62320.0, 8548.401611887108, 8953.32850036263, 9017.639294428149, 9666.275029372966, 9703.12671859536, 13015.804762928374, 13278.734425311633, 13331.866681333062, 13993.090449120775, 14010.439582416997, 11722.94138529302, 11768.882311177122, 11868.584599538975, 11909.285442873108, 12524.499020039097, 12044.21712588679, 12157.0, 12195.268283902995, 12314.261144331369, 12460.937695311466, 12359.0, 12402.317872517098, 12415.751684966432, 12453.688657783629, 12460.688482788002, 8921.598528066383, 9014.368994170345, 9025.548722563977, 9031.096890310917, 9456.527173641152, 9825.606975720892, 11916.285022898654, 12363.814542782045, 12408.128401732196, 12666.936665316614], "value": 9525.837340045582, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.9292535373243, 1502.4215676964368, 1532.2491978930325, 1659.4440862311126, 1784.8764661841776, 8039.477433966788, 8814.911850881497, 9054.637814487422, 9115.817683646323, 9486.430814151156, 9329.626814927395, 9396.671116510923, 9406.341556091076, 9411.341206115558, 9417.43495390277, 2572.9871350643266, 2599.9350016249555, 2601.4536947241104, 2604.609308603706, 2605.778508826752, 2529.456166924111, 2555.0546297869814, 2562.6668533090674, 2563.5898256278906, 2564.0897481394118, 2518.659980902573, 2536.606825941982, 2579.3809485723386, 2583.250857251395, 2584.638150658909, 2452.681151450309, 2489.054159419427, 2500.0499810072056, 2508.673872396586, 2514.962275565868, 2447.131268399728, 2453.914113006036, 2457.0540341968294, 2482.094035676983, 2526.759957804006, 9096.545172741402, 9226.723198304062, 9228.676996305165, 9328.626854925795, 9330.253579713675, 5652.152177173416, 5830.82507524775, 5870.73581688825, 5873.0, 5893.58744887858, 3609.7834129952025, 3650.0, 3682.815859207029, 3737.0470530014672, 3746.7939263340504, 1349.6963183283683, 1363.6727185475513, 1376.5388594820747, 1399.3912647998154, 1403.361470530906, 13115.344232788419, 13192.802107968293, 13216.73566528664, 13221.0, 13232.470701171942, 4775.761211939389, 4780.569748722632, 4851.345068415719, 4974.129527332702, 5011.173156429149, 10392.948035259775, 10405.739856503647, 10408.687739367831, 10408.895911040943, 10472.424016679004, 5552.139418390076, 5552.139418390156, 5591.30108736408, 5597.552195824324, 5599.692016939108, 4419.734815911055, 4607.861764147081, 4895.510448955147, 4895.828645997444, 5225.503577160163, 4150.792460376969, 4188.162367526507, 4255.87232383029, 4301.483821941324, 4325.5674432557125, 1234.4691782533416, 1239.0645062977394, 1249.3503378243263, 1268.0679700420185, 1280.1998750780763, 805.5126648377765, 816.9142240064708, 817.9550124743196, 824.2087595907996, 825.3149885594942, 6146.385361463907, 6376.29860715321, 6401.679916004181, 6481.351864813481, 6597.571157874723, 514.5471984653556, 514.6037551085643, 514.6423235851048, 522.1958184396063, 530.0194639916124, 4293.763842988666, 4427.46870375557, 4430.490493593214, 4536.909261814745, 4552.931706024412, 11218.831717524243, 11511.079113670887, 11633.243839150597, 11642.476088576035, 11654.53381864733, 10855.0, 11351.772964540829, 11403.315801051964, 11554.191206615456, 11583.247088939192, 8439.535825529532, 8647.351448641377, 8652.8702069469, 8653.567321633893, 8658.134186581417, 6304.338044505261, 6353.745850165941, 6390.4248617624635, 6473.2232132144445, 6575.210974683068, 10349.172066234834, 10670.253082284165, 10688.358698478116, 10787.244892857738, 10820.134389248999, 9875.012498750068, 10159.492025398848, 10968.780624387628, 10984.505697243483, 10986.835197471884, 7613.885791713239, 7776.805579860549, 7780.299773020455, 7888.447808653339, 7993.880091798512, 8608.698695545876, 8621.87067194005, 8644.56777161152, 8665.610047548002, 8670.26302764272, 7599.278068583476, 7614.200508946481, 7663.846723065619, 7820.569868657051, 7860.449768516378, 7531.359834413917, 7575.583342915972, 7601.315881570464, 7704.499207551491, 7786.18245084258, 6948.131483564554, 7062.894056589258, 7068.469864760061, 7069.187043490065, 7074.292570742885, 5382.596305277041, 5474.91787623178, 5487.368952570506, 5529.115341545334, 5713.942860571341, 5491.588130890279, 5528.253685752372, 5568.415316391721, 5607.5513958882375, 5660.7169641518585], "value": 4789.505396506741, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.65285367486194, 353.53333599648477, 356.06355285598886, 357.4174096223156, 365.85914422947155, 5458.181272809075, 5502.889942201158, 5520.282363292767, 5551.694656793875, 5565.359983601887, 5180.891465120925, 5200.531952124308, 5335.146376579748, 5396.9190462143, 5407.3781515125775, 4338.3492476128595, 4355.063661312817, 4358.8910277243, 4365.563443655625, 4367.872360619216, 4336.913261734763, 4337.566243375669, 4361.738295702268, 4373.5785869592355, 4384.276594361942, 4143.171365726852, 4325.264705000158, 4367.868963931087, 4381.846635367763, 4388.0, 4334.9783251083545, 4354.303311470181, 4355.281378572532, 4359.084592235635, 4370.868873933787, 3929.8624548140965, 3931.857137859873, 3945.4279129526344, 3945.763254204757, 3947.8026098695236, 5137.383513978347, 5330.040592693314, 5338.46615338463, 5344.625876188668, 5380.946190538123, 4980.526849949249, 4984.87537811554, 4989.501049894981, 4999.350084488993, 5015.247712843066, 5732.426757324234, 5734.913976290359, 5747.252857128588, 5750.607141786324, 5753.597248192628, 4155.501339839208, 4266.658667306608, 4284.1364006656895, 4306.956930430687, 4307.0, 5889.087191485284, 5921.259842519685, 5956.74908269264, 5966.403359664042, 5967.940320596826, 4389.231884420277, 4396.780160991938, 4404.911901761947, 4408.346869923778, 4417.668674849399, 4357.7385356878685, 4423.734575925455, 4455.777211139495, 4470.8435204767675, 4488.618467430239, 4988.625853060962, 4996.850094497171, 5001.349824522788, 5007.599392048627, 5008.874778130576, 5770.394108618619, 5781.653100813965, 5787.739551720183, 5787.971060144757, 5789.681567513827, 1465.5896349022291, 1471.7056588682306, 1501.7221813964452, 1503.9699206015819, 1536.3624096000258, 1069.8127827630133, 1076.1444651502075, 1092.6066616018227, 1096.3367162866512, 1102.8786833448314, 4862.440819305825, 4908.3619129513645, 4930.408350997904, 4932.827351042696, 4941.703497790144, 5141.485851414904, 5146.0479811235045, 5154.226865970059, 5160.612954028463, 5218.791248350023, 4711.952880471152, 4871.806407430202, 4971.45314015458, 4975.776090075955, 4979.402471703346, 2684.3960108975325, 2733.9747594652003, 2742.657167854018, 2752.587111933226, 2752.944941101167, 5147.974260128675, 5191.909698963184, 5199.0, 5231.639773658876, 5269.578433725293, 5013.373328333958, 5107.770150343244, 5192.247124166975, 5208.322918020709, 5222.589900726858, 5145.897082058338, 5216.843494695166, 5235.973820130875, 5239.633225674166, 5240.03498687992, 4995.700257984533, 5110.029094472038, 5115.335006449138, 5116.820911268088, 5120.180771076759, 4841.007593443406, 4928.757415375923, 4991.50084991498, 5013.473585273639, 5065.189569668835, 5155.241945559258, 5211.869703257449, 5218.00857837025, 5229.26790249373, 5234.555062819703, 3924.117073658404, 3933.3116704577155, 3943.402921816669, 3974.682025438016, 3978.423128646388, 4887.462379138363, 4964.304997300309, 5068.569171620454, 5107.770150343318, 5614.494695477427, 5601.551875850004, 5613.8772245550235, 5717.628354156966, 5756.510696590837, 5779.422057794187, 5758.481736643722, 5758.942410575841, 5764.279465066867, 5764.769409223584, 5768.336641286306, 5570.721463926707, 5615.466530679579, 5680.488756011979, 5716.828495145152, 5739.397363276963, 5769.480746732815, 5777.480026797441, 5814.505767009851, 5835.416458354131, 5842.516930304519, 5460.904321758681, 5678.119891416837, 5680.847129842977, 5683.2895888014, 5687.829365119053], "value": 4245.820708539775, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.2640876029094, 841.9368547358949, 896.8071864549121, 899.2536194958185, 957.9185769209616, 15127.319270632821, 16248.268827902742, 16352.0, 16352.509424717262, 16357.836421635766, 15354.0, 15500.53498395047, 16122.677546449064, 16793.16034198291, 16831.495055148363, 729.1286912139982, 756.0436048398752, 857.8069934264803, 886.2422628652474, 897.7665806890191, 884.6903583745669, 884.7699598104507, 887.2236792806294, 887.5384799904047, 887.5695287785419, 771.2518856708983, 943.7216021273706, 944.032366824004, 944.8960614332418, 945.1682519382933, 784.7528028670979, 890.8307421589877, 918.7703074231442, 922.4050487435567, 927.4018258223475, 673.8753330633848, 675.5743881354764, 675.9526833121683, 676.8172593399804, 676.8240257533027, 16957.406490772762, 17001.23494442753, 17009.82990170095, 17010.829891701047, 17055.744163837553, 14932.701345973128, 15484.767728484081, 15712.29294681742, 15725.606859828482, 15982.680346393123, 13438.731225375535, 13539.916806655445, 13713.314334283246, 13715.519956801463, 13768.380422880995, 4828.179209534387, 4866.416030076413, 4895.730734809584, 4927.211646136601, 4952.14706916272, 85771.57114214389, 87369.12630873737, 87957.0, 90736.0, 92545.53727231389, 12770.361481926053, 13121.803172952414, 13141.080124391392, 13361.732765344834, 13395.0, 25070.0, 25180.496390072098, 25190.874045629655, 25404.0, 25417.745822541907, 9783.510824458748, 9818.75453113678, 9891.10980011788, 9899.0, 10146.239032072624, 5160.509751573594, 5174.120399532088, 5198.246254293182, 5255.789768409298, 5312.973435132877, 3747.3442147624055, 3859.4403811447746, 3888.9611103889165, 3904.04350934023, 3904.277708623857, 608.9147519347295, 611.8929187392189, 612.9570930034944, 624.4598422364622, 630.3224034163201, 631.3907079668143, 713.7894321175265, 716.9103862017248, 724.9202587715347, 740.5223630758131, 9032.548372581476, 9621.615135394646, 9713.320067595341, 9875.40747555149, 9908.711867457185, 630.124127462827, 655.2170156662743, 704.5772536478072, 704.6580773042616, 719.6365835253164, 4712.577467026834, 4976.985040276139, 4994.704996196506, 5004.0, 5020.246962955586, 125392.0, 128469.0, 131934.0, 135274.0, 139815.0, 62725.372746272864, 64777.0, 65932.34067659262, 66492.0, 110960.4451977735, 81905.59047204845, 98340.50829745806, 102750.48624756829, 103929.48035259775, 105584.47207763912, 9445.669401570913, 9553.235741140554, 9593.856092158483, 9605.663801766905, 9689.70930872075, 52800.0, 53566.0, 54340.18489722578, 54951.72524137354, 56039.71980140073, 71112.0, 73031.26968730458, 76058.61970690111, 77510.22489775256, 78739.60630196812, 12968.286744229157, 13927.651808704863, 14344.067635603653, 14776.63058423548, 14907.552773416815, 13716.519921802688, 16331.183440827674, 16768.664626707643, 16826.326946921985, 17588.57915457104, 13298.534551290659, 13487.0, 14005.369758361103, 14028.158310501403, 14037.789433158307, 13659.590212293648, 13722.588322350346, 13890.166590004632, 14122.518208121484, 14125.505607303696, 13229.338533073116, 13800.0, 13978.23119728384, 14013.089149205269, 14100.435982560582, 12071.912294346947, 12402.13185077036, 13021.739565208833, 13105.14816536922, 13179.0, 10557.73605659834, 11287.43562821872, 11703.356315402394, 11867.287962722265, 12147.696307592381], "value": 8079.892886572689, "unit": "score"}], "name": "dromaeo_css", "value": 7512.821049486072, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 110.0, 113.0, 111.0, 111.0, 110.0, 110.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 124.0, 162.0, 117.0, 123.0, 116.0, 115.0, 144.0, 115.0], "value": 127.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 160.0, 159.0, 159.0, 160.0, 160.0, 162.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 101.0, 75.0, 76.0, 76.0, 75.0, 98.0, 74.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 110.0, 82.0, 83.0, 81.0, 99.0, 82.0, 82.0, 104.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 48.0, 50.0, 49.0, 50.0, 48.0, 49.0, 49.0, 53.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 73.0, 78.0, 73.0, 73.0, 73.0, 73.0, 81.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 123.0, 123.0, 120.0, 119.0, 117.0, 118.0, 119.0, 145.0, 140.0], "value": 124.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 152.0, 154.0, 153.0, 154.0, 150.0, 152.0, 152.0, 156.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 60.0, 64.0, 60.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1447.4, "alertThreshold": 2.0}]} 04:02:56 INFO - Return code: 0 04:02:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:02:56 INFO - # TBPL SUCCESS # 04:02:56 INFO - Running post-action listener: _resource_record_post_action 04:02:56 INFO - [mozharness: 2016-10-27 11:02:56.890950Z] Finished run-tests step (success) 04:02:56 INFO - Running post-run listener: _resource_record_post_run 04:02:56 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 357974016; Read time: 16; Write time: 236280 04:02:56 INFO - TinderboxPrint: CPU usage
13.0% 04:02:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:02:56 INFO - TinderboxPrint: I/O write bytes / time
357,974,016 / 236,280 04:02:56 INFO - TinderboxPrint: CPU idle
8,260.7 (87.0%) 04:02:56 INFO - TinderboxPrint: CPU user
1,190.7 (12.5%) 04:02:56 INFO - TinderboxPrint: Swap in / out
0 / 0 04:02:56 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 109174784; Read time: 0; Write time: 73128 04:02:56 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248246272; Read time: 0; Write time: 163152 04:02:57 INFO - Running post-run listener: _upload_blobber_files 04:02:57 INFO - Blob upload gear active. 04:02:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:02:57 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 04:02:57 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'] 04:02:57 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 04:02:57 INFO - (blobuploader) - INFO - Open directory for files ... 04:02:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:02:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:02:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:02:59 INFO - (blobuploader) - INFO - Done attempting. 04:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:00 INFO - (blobuploader) - INFO - Done attempting. 04:03:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:03 INFO - (blobuploader) - INFO - Done attempting. 04:03:03 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:03 INFO - Return code: 0 04:03:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb7362d6148c9fe303ab15814e0633b25f4d5fe3e28ddfe690b02ae5300f414340a717898855a9ec078e71cd75873b5df28377c6b3e642497397099cb87d50d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9534798322377389cf34e3dad25bd93e0a1ef682ff026a9bb180575b61cafbbf710152b7ecf3e61b65488c45c7ddd8a25e2712ba02ea8d519a7aac23791387a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f34b286047d777d1611b0649eae762c6e4d245d50bd1b73ea5ba4c5c58e096aaf84cef7c7fc11230dac9966f838913bc64797b9f0e6c2e4e81e71e957f12ba3"} 04:03:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:03 INFO - Contents: 04:03:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cb7362d6148c9fe303ab15814e0633b25f4d5fe3e28ddfe690b02ae5300f414340a717898855a9ec078e71cd75873b5df28377c6b3e642497397099cb87d50d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9534798322377389cf34e3dad25bd93e0a1ef682ff026a9bb180575b61cafbbf710152b7ecf3e61b65488c45c7ddd8a25e2712ba02ea8d519a7aac23791387a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f34b286047d777d1611b0649eae762c6e4d245d50bd1b73ea5ba4c5c58e096aaf84cef7c7fc11230dac9966f838913bc64797b9f0e6c2e4e81e71e957f12ba3"} 04:03:03 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:03 INFO - Copying logs to upload dir... 04:03:03 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.274453 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-27 04:03:03.361080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 04:03:03.365012) ========= 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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 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/3cb7362d6148c9fe303ab15814e0633b25f4d5fe3e28ddfe690b02ae5300f414340a717898855a9ec078e71cd75873b5df28377c6b3e642497397099cb87d50d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9534798322377389cf34e3dad25bd93e0a1ef682ff026a9bb180575b61cafbbf710152b7ecf3e61b65488c45c7ddd8a25e2712ba02ea8d519a7aac23791387a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f34b286047d777d1611b0649eae762c6e4d245d50bd1b73ea5ba4c5c58e096aaf84cef7c7fc11230dac9966f838913bc64797b9f0e6c2e4e81e71e957f12ba3"} build_url:https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011502 build_url: 'https://queue.taskcluster.net/v1/task/IBtwm1LASSW3Utorl8tERA/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/3cb7362d6148c9fe303ab15814e0633b25f4d5fe3e28ddfe690b02ae5300f414340a717898855a9ec078e71cd75873b5df28377c6b3e642497397099cb87d50d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9534798322377389cf34e3dad25bd93e0a1ef682ff026a9bb180575b61cafbbf710152b7ecf3e61b65488c45c7ddd8a25e2712ba02ea8d519a7aac23791387a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f34b286047d777d1611b0649eae762c6e4d245d50bd1b73ea5ba4c5c58e096aaf84cef7c7fc11230dac9966f838913bc64797b9f0e6c2e4e81e71e957f12ba3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-27 04:03:03.391467) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 04:03:03.391813) ========= 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=14ef55db36f7623abb31b9cf000001d5-1477564945.24305-2122878188 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-27 04:03:03.473629) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 04:03:03.473992) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-27 04:03:03.474336) ========= ========= Total master_lag: 0.18 =========