builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1479190968.8 results: success (0) buildid: 20161114193142 builduid: 0d16a01f5ebb497290c9e26805c51cfe revision: 5e76768327660437bf3486554ad318e4b70276e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.802876) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.803413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.803706) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.821839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.822190) ========= 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=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-14 22:22:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-11-14 22:22:49 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.923508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.923842) ========= 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=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018611 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:48.978959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:22:48.979338) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5e76768327660437bf3486554ad318e4b70276e1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5e76768327660437bf3486554ad318e4b70276e1 --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=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-14 22:22:49,253 truncating revision to first 12 chars 2016-11-14 22:22:49,253 Setting DEBUG logging. 2016-11-14 22:22:49,253 attempt 1/10 2016-11-14 22:22:49,253 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5e7676832766?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 22:22:50,539 unpacking tar archive at: mozilla-central-5e7676832766/testing/mozharness/ program finished with exit code 0 elapsedTime=1.631246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 22:22:50.629255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:50.629587) ========= script_repo_revision: 5e76768327660437bf3486554ad318e4b70276e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:50.629999) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:50.634365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 22:22:50.645282) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-11-14 22:22:50.645654) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:22:50 INFO - MultiFileLogger online at 20161114 22:22:50 in /builds/slave/test 22:22:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:22:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:22:51 INFO - {'append_to_log': False, 22:22:51 INFO - 'base_work_dir': '/builds/slave/test', 22:22:51 INFO - 'blob_upload_branch': 'Firefox', 22:22:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:22:51 INFO - 'branch': 'Firefox', 22:22:51 INFO - 'buildbot_json_path': 'buildprops.json', 22:22:51 INFO - 'config_files': ('talos/linux_config.py',), 22:22:51 INFO - 'default_actions': ('clobber', 22:22:51 INFO - 'read-buildbot-config', 22:22:51 INFO - 'download-and-extract', 22:22:51 INFO - 'populate-webroot', 22:22:51 INFO - 'create-virtualenv', 22:22:51 INFO - 'install', 22:22:51 INFO - 'run-tests'), 22:22:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:22:51 INFO - 'download_minidump_stackwalk': True, 22:22:51 INFO - 'download_symbols': 'ondemand', 22:22:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:22:51 INFO - 'tooltool.py': '/tools/tooltool.py', 22:22:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:22:51 INFO - '/tools/misc-python/virtualenv.py')}, 22:22:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:22:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:22:51 INFO - 'installer_path': 'installer.exe', 22:22:51 INFO - 'log_level': 'info', 22:22:51 INFO - 'log_name': 'talos', 22:22:51 INFO - 'log_to_console': True, 22:22:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:22:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:22:51 INFO - 'opt_config_files': (), 22:22:51 INFO - 'pip_index': False, 22:22:51 INFO - 'sps_profile': False, 22:22:51 INFO - 'sps_profile_interval': 0, 22:22:51 INFO - 'suite': 'dromaeojs', 22:22:51 INFO - 'system_bits': '32', 22:22:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:22:51 INFO - 'title': 'talos-linux64-ix-007', 22:22:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:22:51 INFO - 'use_talos_json': True, 22:22:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:22:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:22:51 INFO - 'work_dir': 'build'} 22:22:51 INFO - [mozharness: 2016-11-15 06:22:51.006513Z] Running clobber step. 22:22:51 INFO - Running pre-action listener: _resource_record_pre_action 22:22:51 INFO - Running main action method: clobber 22:22:51 INFO - rmtree: /builds/slave/test/build 22:22:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:22:51 INFO - Running post-action listener: _resource_record_post_action 22:22:51 INFO - [mozharness: 2016-11-15 06:22:51.499310Z] Finished clobber step (success) 22:22:51 INFO - [mozharness: 2016-11-15 06:22:51.499410Z] Running read-buildbot-config step. 22:22:51 INFO - Running pre-action listener: _resource_record_pre_action 22:22:51 INFO - Running main action method: read_buildbot_config 22:22:51 INFO - Using buildbot properties: 22:22:51 INFO - { 22:22:51 INFO - "project": "", 22:22:51 INFO - "product": "firefox", 22:22:51 INFO - "script_repo_revision": "production", 22:22:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:22:51 INFO - "repository": "", 22:22:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:22:51 INFO - "buildid": "20161114193142", 22:22:51 INFO - "pgo_build": "True", 22:22:51 INFO - "basedir": "/builds/slave/test", 22:22:51 INFO - "buildnumber": 68, 22:22:51 INFO - "slavename": "talos-linux64-ix-007", 22:22:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:22:51 INFO - "platform": "ubuntu64_hw", 22:22:51 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:22:51 INFO - "branch": "mozilla-central", 22:22:51 INFO - "repo_path": "mozilla-central", 22:22:51 INFO - "revision": "5e76768327660437bf3486554ad318e4b70276e1", 22:22:51 INFO - "stage_platform": "linux64-pgo", 22:22:51 INFO - "builduid": "0d16a01f5ebb497290c9e26805c51cfe", 22:22:51 INFO - "slavebuilddir": "test" 22:22:51 INFO - } 22:22:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 22:22:51 INFO - Running post-action listener: _resource_record_post_action 22:22:51 INFO - [mozharness: 2016-11-15 06:22:51.501829Z] Finished read-buildbot-config step (success) 22:22:51 INFO - [mozharness: 2016-11-15 06:22:51.501916Z] Running download-and-extract step. 22:22:51 INFO - Running pre-action listener: _resource_record_pre_action 22:22:51 INFO - Running main action method: download_and_extract 22:22:51 INFO - mkdir: /builds/slave/test/build/tests 22:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:51 INFO - trying https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:22:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:22:52 INFO - Downloaded 1361 bytes. 22:22:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:22:52 INFO - Using the following test package requirements: 22:22:52 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:22:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:22:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'jsshell-linux-x86_64.zip'], 22:22:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:22:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:22:52 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:22:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:22:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:52 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:22:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:22:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:22:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:22:52 INFO - Fetch https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:22:52 INFO - Http code: 200 22:22:52 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 22:22:52 INFO - x-amz-cf-id: 0CS55XhkV_Ot875eErosqEOlmye31cYu4wWLqcpD9WSF4i-vevYDfw== 22:22:52 INFO - x-amz-version-id: J58qyd4dKqbZABA1QbHXZHRB70dZ.L7V 22:22:52 INFO - Expected file size: 21969097 22:22:52 INFO - Obtained file size: 21969097 22:22:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:22:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:22:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:22:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:22:57 INFO - Http code: 200 22:22:57 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 22:22:57 INFO - x-amz-cf-id: Jeq-Tgyp-e4V_EmE1Sp1hDGEYLKRb9N41y5XRwY2TAnFJHVpL81Qpw== 22:22:57 INFO - x-amz-version-id: J58qyd4dKqbZABA1QbHXZHRB70dZ.L7V 22:22:57 INFO - Expected file size: 21969097 22:22:57 INFO - Obtained file size: 21969097 22:23:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 22:23:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:23:02 INFO - Fetch https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:23:02 INFO - Http code: 200 22:23:02 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 22:23:02 INFO - x-amz-cf-id: 9KpsHtAI5v2rHRId3DflKXHVQp9lkY7AzYwMiECseSFS5ImhiwS0KQ== 22:23:02 INFO - x-amz-version-id: tYxssLNwDV6njBbfezX1EQXGl7vus7Se 22:23:02 INFO - Expected file size: 14409038 22:23:02 INFO - Obtained file size: 14409038 22:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:03 INFO - trying https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:23:03 INFO - Downloading https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:23:04 INFO - Downloaded 59511950 bytes. 22:23:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:23:04 INFO - mkdir: /builds/slave/test/properties 22:23:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:04 INFO - Contents: 22:23:04 INFO - build_url:https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:23:05 INFO - Running post-action listener: _resource_record_post_action 22:23:05 INFO - Running post-action listener: set_extra_try_arguments 22:23:05 INFO - [mozharness: 2016-11-15 06:23:05.028881Z] Finished download-and-extract step (success) 22:23:05 INFO - [mozharness: 2016-11-15 06:23:05.029108Z] Running populate-webroot step. 22:23:05 INFO - Running pre-action listener: _resource_record_pre_action 22:23:05 INFO - Running main action method: populate_webroot 22:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:05 INFO - '--tppagecycles', 22:23:05 INFO - '7'], 22:23:05 INFO - 'tests': ['tsvgm']}}, 22:23:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['damp', 'tps']}, 22:23:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'tests': ['damp', 'tps']}, 22:23:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['dromaeo_dom']}, 22:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['basic_compositor_video']}, 22:23:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:23:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['a11yr', 22:23:05 INFO - 'ts_paint', 22:23:05 INFO - 'tpaint', 22:23:05 INFO - 'sessionrestore', 22:23:05 INFO - 'sessionrestore_no_auto_restore', 22:23:05 INFO - 'tabpaint']}, 22:23:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['tsvgx', 22:23:05 INFO - 'tsvgr_opacity', 22:23:05 INFO - 'tart', 22:23:05 INFO - 'tscrollx', 22:23:05 INFO - 'cart']}, 22:23:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:05 INFO - 'tsvgr_opacity', 22:23:05 INFO - 'tart', 22:23:05 INFO - 'tscrollx', 22:23:05 INFO - 'cart']}, 22:23:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'talos_options': ['--disable-e10s'], 22:23:05 INFO - 'tests': ['tp5o']}, 22:23:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'tests': ['tp5o']}, 22:23:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'talos_options': ['--disable-e10s', 22:23:05 INFO - '--xperf_path', 22:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:05 INFO - 'tests': ['tp5n']}, 22:23:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:05 INFO - 'talos_options': ['--xperf_path', 22:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:05 INFO - 'tests': ['tp5n']}}, 22:23:05 INFO - 'talos.zip': {'path': '', 22:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:05 INFO - Running post-action listener: _resource_record_post_action 22:23:05 INFO - [mozharness: 2016-11-15 06:23:05.051000Z] Finished populate-webroot step (success) 22:23:05 INFO - [mozharness: 2016-11-15 06:23:05.051088Z] Running create-virtualenv step. 22:23:05 INFO - Running pre-action listener: _resource_record_pre_action 22:23:05 INFO - Running main action method: create_virtualenv 22:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:05 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 22:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:05 INFO - Using real prefix '/usr' 22:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:07 INFO - Installing pip.................done. 22:23:07 INFO - Return code: 0 22:23:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:23:07 INFO - Reading from file tmpfile_stdout 22:23:07 INFO - Output received: 22:23:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:23:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:07 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:07 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 22:23:07 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 22:23:07 INFO - Using env: {'DISPLAY': ':0', 22:23:07 INFO - 'HOME': '/home/cltbld', 22:23:07 INFO - 'LANG': 'en_US.UTF-8', 22:23:07 INFO - 'LANGUAGE': 'en_US:en', 22:23:07 INFO - 'LOGNAME': 'cltbld', 22:23:07 INFO - 'MAIL': '/var/mail/cltbld', 22:23:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:07 INFO - 'MOZ_NO_REMOTE': '1', 22:23:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:07 INFO - 'NO_EM_RESTART': '1', 22:23:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:07 INFO - 'PWD': '/builds/slave/test', 22:23:07 INFO - 'SHELL': '/bin/bash', 22:23:07 INFO - 'SHLVL': '1', 22:23:07 INFO - 'TERM': 'linux', 22:23:07 INFO - 'TMOUT': '86400', 22:23:07 INFO - 'USER': 'cltbld', 22:23:07 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:07 INFO - '_': '/tools/buildbot/bin/python'} 22:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:08 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 22:23:08 INFO - Cleaning up... 22:23:08 INFO - Return code: 0 22:23:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:23:08 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')]} 22:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:08 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')]} 22:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:08 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:08 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 22:23:08 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 22:23:08 INFO - Using env: {'DISPLAY': ':0', 22:23:08 INFO - 'HOME': '/home/cltbld', 22:23:08 INFO - 'LANG': 'en_US.UTF-8', 22:23:08 INFO - 'LANGUAGE': 'en_US:en', 22:23:08 INFO - 'LOGNAME': 'cltbld', 22:23:08 INFO - 'MAIL': '/var/mail/cltbld', 22:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:08 INFO - 'MOZ_NO_REMOTE': '1', 22:23:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:08 INFO - 'NO_EM_RESTART': '1', 22:23:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:08 INFO - 'PWD': '/builds/slave/test', 22:23:08 INFO - 'SHELL': '/bin/bash', 22:23:08 INFO - 'SHLVL': '1', 22:23:08 INFO - 'TERM': 'linux', 22:23:08 INFO - 'TMOUT': '86400', 22:23:08 INFO - 'USER': 'cltbld', 22:23:08 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:08 INFO - '_': '/tools/buildbot/bin/python'} 22:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:08 INFO - Downloading/unpacking psutil>=3.1.1 22:23:08 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 22:23:08 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 22:23:08 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 22:23:08 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 22:23:08 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 22:23:08 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 22:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:10 INFO - Installing collected packages: psutil 22:23:10 INFO - Running setup.py install for psutil 22:23:10 INFO - building 'psutil._psutil_linux' extension 22:23:10 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 22:23:10 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 22:23:10 INFO - building 'psutil._psutil_posix' extension 22:23:10 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 22:23:10 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 22:23:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:10 INFO - Successfully installed psutil 22:23:10 INFO - Cleaning up... 22:23:10 INFO - Return code: 0 22:23:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:23:10 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')]} 22:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:10 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')]} 22:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:10 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:10 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 22:23:10 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 22:23:10 INFO - Using env: {'DISPLAY': ':0', 22:23:10 INFO - 'HOME': '/home/cltbld', 22:23:10 INFO - 'LANG': 'en_US.UTF-8', 22:23:10 INFO - 'LANGUAGE': 'en_US:en', 22:23:10 INFO - 'LOGNAME': 'cltbld', 22:23:10 INFO - 'MAIL': '/var/mail/cltbld', 22:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:10 INFO - 'MOZ_NO_REMOTE': '1', 22:23:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:10 INFO - 'NO_EM_RESTART': '1', 22:23:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:10 INFO - 'PWD': '/builds/slave/test', 22:23:10 INFO - 'SHELL': '/bin/bash', 22:23:10 INFO - 'SHLVL': '1', 22:23:10 INFO - 'TERM': 'linux', 22:23:10 INFO - 'TMOUT': '86400', 22:23:10 INFO - 'USER': 'cltbld', 22:23:10 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:10 INFO - '_': '/tools/buildbot/bin/python'} 22:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:23:11 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 22:23:11 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 22:23:11 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 22:23:11 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 22:23:11 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 22:23:11 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 22:23:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:23:13 INFO - Installing collected packages: mozsystemmonitor 22:23:13 INFO - Running setup.py install for mozsystemmonitor 22:23:13 INFO - Successfully installed mozsystemmonitor 22:23:13 INFO - Cleaning up... 22:23:13 INFO - Return code: 0 22:23:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:23:13 INFO - Using env: {'DISPLAY': ':0', 22:23:13 INFO - 'HOME': '/home/cltbld', 22:23:13 INFO - 'LANG': 'en_US.UTF-8', 22:23:13 INFO - 'LANGUAGE': 'en_US:en', 22:23:13 INFO - 'LOGNAME': 'cltbld', 22:23:13 INFO - 'MAIL': '/var/mail/cltbld', 22:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:13 INFO - 'MOZ_NO_REMOTE': '1', 22:23:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:13 INFO - 'NO_EM_RESTART': '1', 22:23:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:13 INFO - 'PWD': '/builds/slave/test', 22:23:13 INFO - 'SHELL': '/bin/bash', 22:23:13 INFO - 'SHLVL': '1', 22:23:13 INFO - 'TERM': 'linux', 22:23:13 INFO - 'TMOUT': '86400', 22:23:13 INFO - 'USER': 'cltbld', 22:23:13 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:13 INFO - '_': '/tools/buildbot/bin/python'} 22:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:13 INFO - Downloading/unpacking jsonschema==2.5.1 22:23:13 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 22:23:13 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 22:23:13 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 22:23:13 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 22:23:13 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 22:23:13 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 22:23:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:23:15 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 22:23:15 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 22:23:15 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 22:23:15 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 22:23:15 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 22:23:15 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 22:23:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:15 INFO - warning: no files found matching '*.txt' 22:23:15 INFO - no previously-included directories found matching 'build' 22:23:15 INFO - no previously-included directories found matching 'dist' 22:23:15 INFO - no previously-included directories found matching '.git*' 22:23:15 INFO - Installing collected packages: jsonschema, functools32 22:23:15 INFO - Running setup.py install for functools32 22:23:15 INFO - warning: no files found matching '*.txt' 22:23:15 INFO - no previously-included directories found matching 'build' 22:23:15 INFO - no previously-included directories found matching 'dist' 22:23:15 INFO - no previously-included directories found matching '.git*' 22:23:15 INFO - Successfully installed jsonschema functools32 22:23:15 INFO - Cleaning up... 22:23:15 INFO - Return code: 0 22:23:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:23:15 INFO - Using env: {'DISPLAY': ':0', 22:23:15 INFO - 'HOME': '/home/cltbld', 22:23:15 INFO - 'LANG': 'en_US.UTF-8', 22:23:15 INFO - 'LANGUAGE': 'en_US:en', 22:23:15 INFO - 'LOGNAME': 'cltbld', 22:23:15 INFO - 'MAIL': '/var/mail/cltbld', 22:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:15 INFO - 'MOZ_NO_REMOTE': '1', 22:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:15 INFO - 'NO_EM_RESTART': '1', 22:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:15 INFO - 'PWD': '/builds/slave/test', 22:23:15 INFO - 'SHELL': '/bin/bash', 22:23:15 INFO - 'SHLVL': '1', 22:23:15 INFO - 'TERM': 'linux', 22:23:15 INFO - 'TMOUT': '86400', 22:23:15 INFO - 'USER': 'cltbld', 22:23:15 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:15 INFO - '_': '/tools/buildbot/bin/python'} 22:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:23:16 INFO - Cleaning up... 22:23:16 INFO - Return code: 0 22:23:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23:16 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')]} 22:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:16 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')]} 22:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:16 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:16 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 22:23:16 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 22:23:16 INFO - Using env: {'DISPLAY': ':0', 22:23:16 INFO - 'HOME': '/home/cltbld', 22:23:16 INFO - 'LANG': 'en_US.UTF-8', 22:23:16 INFO - 'LANGUAGE': 'en_US:en', 22:23:16 INFO - 'LOGNAME': 'cltbld', 22:23:16 INFO - 'MAIL': '/var/mail/cltbld', 22:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:16 INFO - 'MOZ_NO_REMOTE': '1', 22:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:16 INFO - 'NO_EM_RESTART': '1', 22:23:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:16 INFO - 'PWD': '/builds/slave/test', 22:23:16 INFO - 'SHELL': '/bin/bash', 22:23:16 INFO - 'SHLVL': '1', 22:23:16 INFO - 'TERM': 'linux', 22:23:16 INFO - 'TMOUT': '86400', 22:23:16 INFO - 'USER': 'cltbld', 22:23:16 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:16 INFO - '_': '/tools/buildbot/bin/python'} 22:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:23:16 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 22:23:16 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 22:23:16 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 22:23:16 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 22:23:16 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 22:23:16 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 22:23:18 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 INFO - Downloading docopt-0.6.1.tar.gz 22:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:19 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:19 INFO - Running setup.py install for blobuploader 22:23:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:19 INFO - Running setup.py install for requests 22:23:19 INFO - Running setup.py install for docopt 22:23:19 INFO - Successfully installed blobuploader requests docopt 22:23:19 INFO - Cleaning up... 22:23:19 INFO - Return code: 0 22:23:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:19 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:19 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 22:23:19 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 22:23:19 INFO - Using env: {'DISPLAY': ':0', 22:23:19 INFO - 'HOME': '/home/cltbld', 22:23:19 INFO - 'LANG': 'en_US.UTF-8', 22:23:19 INFO - 'LANGUAGE': 'en_US:en', 22:23:19 INFO - 'LOGNAME': 'cltbld', 22:23:19 INFO - 'MAIL': '/var/mail/cltbld', 22:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:19 INFO - 'MOZ_NO_REMOTE': '1', 22:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:19 INFO - 'NO_EM_RESTART': '1', 22:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:19 INFO - 'PWD': '/builds/slave/test', 22:23:19 INFO - 'SHELL': '/bin/bash', 22:23:19 INFO - 'SHLVL': '1', 22:23:19 INFO - 'TERM': 'linux', 22:23:19 INFO - 'TMOUT': '86400', 22:23:19 INFO - 'USER': 'cltbld', 22:23:19 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:19 INFO - '_': '/tools/buildbot/bin/python'} 22:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:19 INFO - Running setup.py (path:/tmp/pip-0Z045q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:19 INFO - Running setup.py (path:/tmp/pip-LICfrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:19 INFO - Running setup.py (path:/tmp/pip-QyKsr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:20 INFO - Running setup.py (path:/tmp/pip-M8UoQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:20 INFO - Running setup.py (path:/tmp/pip-kR3Z1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:20 INFO - Running setup.py (path:/tmp/pip-qFL7th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:20 INFO - Running setup.py (path:/tmp/pip-5Zxoyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:20 INFO - Running setup.py (path:/tmp/pip-rJPAWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:20 INFO - Running setup.py (path:/tmp/pip-aOrrGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:20 INFO - Running setup.py (path:/tmp/pip-xIE8Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:20 INFO - Running setup.py (path:/tmp/pip-2kZAna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:20 INFO - Running setup.py (path:/tmp/pip-J2Sk3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:20 INFO - Running setup.py (path:/tmp/pip-h7J3Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:20 INFO - Running setup.py (path:/tmp/pip-XVf0lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:20 INFO - Running setup.py (path:/tmp/pip-7BcvvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:20 INFO - Running setup.py (path:/tmp/pip-8eiLPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:21 INFO - Running setup.py (path:/tmp/pip-VWP5w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:21 INFO - Running setup.py install for manifestparser 22:23:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Running setup.py install for mozcrash 22:23:21 INFO - Running setup.py install for mozdebug 22:23:21 INFO - Running setup.py install for mozdevice 22:23:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Running setup.py install for mozfile 22:23:21 INFO - Running setup.py install for mozhttpd 22:23:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Running setup.py install for mozinfo 22:23:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Running setup.py install for mozInstall 22:23:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:21 INFO - Running setup.py install for mozleak 22:23:21 INFO - Running setup.py install for mozlog 22:23:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Running setup.py install for moznetwork 22:23:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Running setup.py install for mozprocess 22:23:22 INFO - Running setup.py install for mozprofile 22:23:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Running setup.py install for mozrunner 22:23:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Running setup.py install for mozscreenshot 22:23:22 INFO - Running setup.py install for moztest 22:23:22 INFO - Running setup.py install for mozversion 22:23:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:23:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:23:22 INFO - Cleaning up... 22:23:22 INFO - Return code: 0 22:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:22 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:22 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 22:23:22 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 22:23:22 INFO - Using env: {'DISPLAY': ':0', 22:23:22 INFO - 'HOME': '/home/cltbld', 22:23:22 INFO - 'LANG': 'en_US.UTF-8', 22:23:22 INFO - 'LANGUAGE': 'en_US:en', 22:23:22 INFO - 'LOGNAME': 'cltbld', 22:23:22 INFO - 'MAIL': '/var/mail/cltbld', 22:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:22 INFO - 'MOZ_NO_REMOTE': '1', 22:23:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:22 INFO - 'NO_EM_RESTART': '1', 22:23:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:22 INFO - 'PWD': '/builds/slave/test', 22:23:22 INFO - 'SHELL': '/bin/bash', 22:23:22 INFO - 'SHLVL': '1', 22:23:22 INFO - 'TERM': 'linux', 22:23:22 INFO - 'TMOUT': '86400', 22:23:22 INFO - 'USER': 'cltbld', 22:23:22 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:22 INFO - '_': '/tools/buildbot/bin/python'} 22:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:23 INFO - Running setup.py (path:/tmp/pip-2ucX0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:23 INFO - Running setup.py (path:/tmp/pip-9MC35R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:23 INFO - Running setup.py (path:/tmp/pip-N4WCLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:23 INFO - Running setup.py (path:/tmp/pip-4JH_KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:23 INFO - Running setup.py (path:/tmp/pip-Cb8ZpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:23 INFO - Running setup.py (path:/tmp/pip-PbFaJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:23 INFO - Running setup.py (path:/tmp/pip-VIKGcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:23 INFO - Running setup.py (path:/tmp/pip-IN0iJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:23 INFO - Running setup.py (path:/tmp/pip-Kcj0Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:23 INFO - Running setup.py (path:/tmp/pip-E7EZWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:23 INFO - Running setup.py (path:/tmp/pip-Q_NP1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:23 INFO - Running setup.py (path:/tmp/pip-wY0_tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:23 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)) 22:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:23 INFO - Running setup.py (path:/tmp/pip-aL0JDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:24 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)) 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:24 INFO - Running setup.py (path:/tmp/pip-ew2ZCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:24 INFO - Running setup.py (path:/tmp/pip-TMS7Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:24 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)) 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:24 INFO - Running setup.py (path:/tmp/pip-O2q8lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:24 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)) 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:24 INFO - Running setup.py (path:/tmp/pip-8jMQq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:24 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)) 22:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:23:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:23:24 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)) 22:23:24 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)) 22:23:24 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)) 22:23:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:23:24 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 22:23:24 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 22:23:24 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 22:23:24 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 22:23:24 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 22:23:24 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 22:23:26 INFO - Downloading blessings-1.6.tar.gz 22:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:23:26 INFO - Installing collected packages: blessings 22:23:26 INFO - Running setup.py install for blessings 22:23:26 INFO - Successfully installed blessings 22:23:26 INFO - Cleaning up... 22:23:26 INFO - Return code: 0 22:23:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:23:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:26 INFO - Reading from file tmpfile_stdout 22:23:26 INFO - Current package versions: 22:23:26 INFO - argparse == 1.2.1 22:23:26 INFO - blessings == 1.6 22:23:26 INFO - blobuploader == 1.2.4 22:23:26 INFO - docopt == 0.6.1 22:23:26 INFO - functools32 == 3.2.3-2 22:23:26 INFO - jsonschema == 2.5.1 22:23:26 INFO - manifestparser == 1.1 22:23:26 INFO - mozInstall == 1.12 22:23:26 INFO - mozcrash == 1.0 22:23:26 INFO - mozdebug == 0.1 22:23:26 INFO - mozdevice == 0.48 22:23:26 INFO - mozfile == 1.2 22:23:26 INFO - mozhttpd == 0.7 22:23:26 INFO - mozinfo == 0.9 22:23:26 INFO - mozleak == 0.1 22:23:26 INFO - mozlog == 3.4 22:23:26 INFO - moznetwork == 0.27 22:23:26 INFO - mozprocess == 0.23 22:23:26 INFO - mozprofile == 0.28 22:23:26 INFO - mozrunner == 6.13 22:23:26 INFO - mozscreenshot == 0.1 22:23:26 INFO - mozsystemmonitor == 0.3 22:23:26 INFO - moztest == 0.7 22:23:26 INFO - mozversion == 1.4 22:23:26 INFO - psutil == 3.1.1 22:23:26 INFO - requests == 1.2.3 22:23:26 INFO - wsgiref == 0.1.2 22:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:26 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')]} 22:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:26 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')]} 22:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:26 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:26 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 22:23:26 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 22:23:26 INFO - Using env: {'DISPLAY': ':0', 22:23:26 INFO - 'HOME': '/home/cltbld', 22:23:26 INFO - 'LANG': 'en_US.UTF-8', 22:23:26 INFO - 'LANGUAGE': 'en_US:en', 22:23:26 INFO - 'LOGNAME': 'cltbld', 22:23:26 INFO - 'MAIL': '/var/mail/cltbld', 22:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:26 INFO - 'MOZ_NO_REMOTE': '1', 22:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:26 INFO - 'NO_EM_RESTART': '1', 22:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:26 INFO - 'PWD': '/builds/slave/test', 22:23:26 INFO - 'SHELL': '/bin/bash', 22:23:26 INFO - 'SHLVL': '1', 22:23:26 INFO - 'TERM': 'linux', 22:23:26 INFO - 'TMOUT': '86400', 22:23:26 INFO - 'USER': 'cltbld', 22:23:26 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:26 INFO - '_': '/tools/buildbot/bin/python'} 22:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 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)) 22:23:26 INFO - Cleaning up... 22:23:26 INFO - Return code: 0 22:23:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:23:26 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')]} 22:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:26 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')]} 22:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:26 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 0x7f7823d35f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x217e790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, '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': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', '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 22:23:26 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 22:23:26 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 22:23:26 INFO - Using env: {'DISPLAY': ':0', 22:23:26 INFO - 'HOME': '/home/cltbld', 22:23:26 INFO - 'LANG': 'en_US.UTF-8', 22:23:26 INFO - 'LANGUAGE': 'en_US:en', 22:23:26 INFO - 'LOGNAME': 'cltbld', 22:23:26 INFO - 'MAIL': '/var/mail/cltbld', 22:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:26 INFO - 'MOZ_NO_REMOTE': '1', 22:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:26 INFO - 'NO_EM_RESTART': '1', 22:23:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:26 INFO - 'PWD': '/builds/slave/test', 22:23:26 INFO - 'SHELL': '/bin/bash', 22:23:26 INFO - 'SHLVL': '1', 22:23:26 INFO - 'TERM': 'linux', 22:23:26 INFO - 'TMOUT': '86400', 22:23:26 INFO - 'USER': 'cltbld', 22:23:26 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:26 INFO - '_': '/tools/buildbot/bin/python'} 22:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:23:27 INFO - Cleaning up... 22:23:27 INFO - Return code: 0 22:23:27 INFO - Running post-action listener: _resource_record_post_action 22:23:27 INFO - Running post-action listener: _start_resource_monitoring 22:23:27 INFO - Starting resource monitoring. 22:23:27 INFO - [mozharness: 2016-11-15 06:23:27.246559Z] Finished create-virtualenv step (success) 22:23:27 INFO - [mozharness: 2016-11-15 06:23:27.247085Z] Running install step. 22:23:27 INFO - Running pre-action listener: _resource_record_pre_action 22:23:27 INFO - Running main action method: install 22:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:27 INFO - Reading from file tmpfile_stdout 22:23:27 INFO - Detecting whether we're running mozinstall >=1.0... 22:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:23:27 INFO - Reading from file tmpfile_stdout 22:23:27 INFO - Output received: 22:23:27 INFO - Usage: mozinstall [options] installer 22:23:27 INFO - Options: 22:23:27 INFO - -h, --help show this help message and exit 22:23:27 INFO - -d DEST, --destination=DEST 22:23:27 INFO - Directory to install application into. [default: 22:23:27 INFO - "/builds/slave/test"] 22:23:27 INFO - --app=APP Application being installed. [default: firefox] 22:23:27 INFO - mkdir: /builds/slave/test/build/application 22:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:23:44 INFO - Reading from file tmpfile_stdout 22:23:44 INFO - Output received: 22:23:44 INFO - /builds/slave/test/build/application/firefox/firefox 22:23:44 INFO - Running post-action listener: _resource_record_post_action 22:23:44 INFO - [mozharness: 2016-11-15 06:23:44.427985Z] Finished install step (success) 22:23:44 INFO - [mozharness: 2016-11-15 06:23:44.428229Z] Running run-tests step. 22:23:44 INFO - Running pre-action listener: _resource_record_pre_action 22:23:44 INFO - Running main action method: run_tests 22:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:23:44 INFO - Python 2.7.3 22:23:44 INFO - Return code: 0 22:23:44 INFO - grabbing minidump binary from tooltool 22:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:44 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 0x217b930>, '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 0x217c830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x217ccc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:23:44 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 22:23:44 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 22:23:44 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 22:23:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:23:44 INFO - Return code: 0 22:23:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:23:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:23:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:23:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:23:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 22:23:44 INFO - Using env: {'DISPLAY': ':0', 22:23:44 INFO - 'HOME': '/home/cltbld', 22:23:44 INFO - 'LANG': 'en_US.UTF-8', 22:23:44 INFO - 'LANGUAGE': 'en_US:en', 22:23:44 INFO - 'LOGNAME': 'cltbld', 22:23:44 INFO - 'MAIL': '/var/mail/cltbld', 22:23:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:23:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:44 INFO - 'MOZ_NO_REMOTE': '1', 22:23:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:23:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:44 INFO - 'NO_EM_RESTART': '1', 22:23:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:44 INFO - 'PWD': '/builds/slave/test', 22:23:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:23:44 INFO - 'SHELL': '/bin/bash', 22:23:44 INFO - 'SHLVL': '1', 22:23:44 INFO - 'TERM': 'linux', 22:23:44 INFO - 'TMOUT': '86400', 22:23:44 INFO - 'USER': 'cltbld', 22:23:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1479190962.113172-982574626', 22:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:44 INFO - '_': '/tools/buildbot/bin/python'} 22:23:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 22:23:44 INFO - mozversion application_buildid: 20161114193142 22:23:44 INFO - mozversion application_changeset: 5e76768327660437bf3486554ad318e4b70276e1 22:23:44 INFO - mozversion application_display_name: Nightly 22:23:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:23:44 INFO - mozversion application_name: Firefox 22:23:44 INFO - mozversion application_remotingname: firefox 22:23:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:23:44 INFO - mozversion application_vendor: Mozilla 22:23:44 INFO - mozversion application_version: 53.0a1 22:23:44 INFO - mozversion platform_buildid: 20161114193142 22:23:44 INFO - mozversion platform_changeset: 5e76768327660437bf3486554ad318e4b70276e1 22:23:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:23:44 INFO - mozversion platform_version: 53.0a1 22:23:44 INFO - using testdate: 1479191024 22:23:44 INFO - actual date: 1479191024 22:23:44 INFO - starting webserver on 'localhost:47371' 22:23:44 INFO - SUITE-START | Running 2 tests 22:23:44 INFO - TEST-START | dromaeo_css 22:23:44 INFO - operating with platform_type : linux_ 22:23:44 INFO - Initialising browser for dromaeo_css test... 22:23:44 INFO - TEST-INFO | started process 12076 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe32UGN/profile http://localhost:47371/getInfo.html) 22:23:48 INFO - PROCESS | 12076 | __metrics Screen width/height:1600/1200 22:23:48 INFO - PROCESS | 12076 | colorDepth:24 22:23:48 INFO - PROCESS | 12076 | Browser inner width/height: 1024/697 22:23:48 INFO - PROCESS | 12076 | __metrics 22:23:48 INFO - PROCESS | 12076 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:23:48 INFO - PROCESS | 12076 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:23:49 INFO - PROCESS | 12076 | console.error: PushService: 22:23:49 INFO - PROCESS | 12076 | stateChangeProcessEnqueue: Error transitioning state 22:23:49 INFO - PROCESS | 12076 | UnknownError 22:23:50 INFO - TEST-INFO | 12076: exit 0 22:23:50 INFO - Browser initialized. 22:23:50 INFO - Running cycle 1/1 for dromaeo_css test... 22:23:50 INFO - TEST-INFO | started process 12161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe32UGN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:02 INFO - PROCESS | 12161 | RSS: Main: 173080576 22:24:02 INFO - PROCESS | 12161 | 22:24:53 INFO - PROCESS | 12161 | 1479191093349 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] 22:27:03 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47371/tests/dromaeo/cssquery-ext.html) 22:27:06 INFO - PROCESS | 12161 | RSS: Main: 432095232 22:27:06 INFO - PROCESS | 12161 | 22:30:09 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-ext.html (next: http://localhost:47371/tests/dromaeo/cssquery-jquery.html) 22:30:09 INFO - PROCESS | 12161 | RSS: Main: 290029568 22:30:09 INFO - PROCESS | 12161 | 22:33:11 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47371/tests/dromaeo/cssquery-mootools.html) 22:33:12 INFO - PROCESS | 12161 | RSS: Main: 227717120 22:33:12 INFO - PROCESS | 12161 | 22:36:14 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47371/tests/dromaeo/cssquery-prototype.html) 22:36:15 INFO - PROCESS | 12161 | RSS: Main: 253472768 22:36:15 INFO - PROCESS | 12161 | 22:39:17 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47371/tests/dromaeo/cssquery-yui.html) 22:39:18 INFO - PROCESS | 12161 | RSS: Main: 226258944 22:39:18 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | Cycle 1(1): loaded http://localhost:47371/tests/dromaeo/cssquery-yui.html (next: http://localhost:47371/tests/dromaeo/cssquery-dojo.html) 22:42:21 INFO - PROCESS | 12161 | RSS: Main: 318693376 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | __start_tp_report 22:42:21 INFO - PROCESS | 12161 | _x_x_mozilla_page_load 22:42:21 INFO - PROCESS | 12161 | _x_x_mozilla_page_load_details 22:42:21 INFO - PROCESS | 12161 | |i|pagename|runs| 22:42:21 INFO - PROCESS | 12161 | |0;dojo.html;3067.5552044953483;3103.953440698391;3129.499280115182;3141.293209027969;3145.4180976519347;5166.741662916858;5198.324217851674;5238.502342277482;5301.310829592152;5325.147976323789;5212.66117702349;5214.296070030545;5219.217117432387;5229.450907654698;5232.16285394337;4704.364910737049;4734.195186818232;4746.620270378378;4761.666683332168;4803.327534145223;4854.58736007438;4864.975675121619;4893.070316639845;4894.8286809961655;4896.534829191221;4676.953230467686;4714.740689262089;4737.289406589005;4740.217864052444;4862.100511405383;4757.9286310705365;4766.642501812378;4791.3531673224015;4793.496682848286;4830.2030165022725;4685.1332503486965;4695.506971767949;4725.598324142452;4733.739644319561;4748;5225.529702326809;5227.843164705065;5263.0526505229045;5264.473552644743;5320.707361095138;5590.161475778666;5654.123610840326;5656;5657.717114144318;5661.971690141564;5505;5624.100143976944;5651.095824668075;5720.170575266605;5774.16274640179;5309.070912590281;5312.867178320573;5334.0665383557725;5362.570994320445;5366.677999319975;5673.971630141823;5737.512311453574;5756.424357564294;5760.711964401763;5766.625169363978;5386.272853164851;5435.537979271727;5483;5493.340799104133;5533.446655334435;5624.55003599711;5647.124695672177;5689.231953686282;5715.1713001614535;5766.596338256281;5655.830325090254;5696.3164420269;5703.686297253608;5708.400617935057;5710.314762228559;5504.119340905436;5553.639013464192;5684.772609095672;5750.539956803445;5780.797672081457;4320.546342634041;4368;4368.912621747547;4369.366441865912;4423.911521769611;3915.451836742858;3924.781639331247;3930.331843586597;3942.842286308573;3959.9604003959594;5386.380566234855;5386.542143917811;5391.973040134852;5400.594955378284;5402.783888644489;5575.191597218381;5652.547796176296;5657.745401456862;5725.713714314351;5734.598578099576;5446.7276636168035;5448.101063324586;5449.863753406117;5454.563634909197;5482.643628164156;4949.975250123726;4984.501549844987;4997.525235102659;5001.474845141208;5044.924326135111;5609.775608975677;5609.80365687199;5690.658560486384;5757.337906140848;5926.644401335933;5632.85917852057;5638.21065050885;5646.520045795989;5661.122526008476;5684;5794.942050579609;5801.245838041028;5802.477776999922;5804.912926306024;5805.680687562224;5491.890162196813;5523.558115350843;5527.613067085265;5551.833444996495;5551.944480555304;5630.042892708331;5695;5696.088625819849;5697.4587414195585;5700.857478563069;5485.259489968803;5531.8893622128135;5544.667319960815;5546.556275498031;5547.44525547442;5546.639468434701;5551.611387202857;5551.805686800943;5553.0837411828215;5554.250176226158;5477.863053423696;5480.890382192413;5481.205225242238;5485.01269771445;5491.807786727445;5515.365732940763;5542.2795036644975;5544.584156188222;5545.722713864371;5548.445155484581;5279.656822306538;5365.356157261153;5381.542568881689;5419.485148910847;5484.753186106715 22:42:21 INFO - PROCESS | 12161 | |1;ext.html;593.661612880658;606.766394937949;610.4627927423863;610.6946526736631;611.4955161991359;31509;40912.38631420531;42231.366529502004;42296.17520758658;45797.77101114498;42502.574974250245;42853.78573107138;42896.57103428965;42924.57075429245;42968.35547466783;8541.487510749344;8683.044865064869;8691.739247822576;8695.608697608592;8699.739007829776;8428.030776460693;8494.447860889053;8499.405041647087;8507.149285071506;8509.787255318635;8551.102134275905;8583.914160858405;8589.871151932726;8595.699150529732;8606.913930860674;8476.194761497647;8477.406581539295;8498.235158835703;8511.27654149398;8526.658933642648;8503.23470887623;8557.10150434208;8569.22876941072;8572.099929507374;8572.228499435018;40251;40294.194116117804;40681.59318406808;40727.7963610183;40732.38901416452;3350.011746534754;3375.729941604666;3401.914952126192;3423.400904841643;3454.24006718524;30147.246318841986;30791.692083079106;30915.690843091732;30924.144551326994;31015.379692406026;4027.8993025174314;4089.9591004089875;4178.874633760992;4200.474940632421;4230.894227644303;321049;321680.3915980405;322279.9999999977;323195;323417;34256.31487370239;34794.47808282878;50072;50245.74877125664;54311.45688543143;36471.4529282061;36621.450678239846;36731.4490282646;36825.81587092047;36871;13767;13961.162330260217;13970.441182352592;13979.930100349433;13982.370793314325;10858.782824343627;10976.560937562568;10983.121350291958;10989.175811813986;10993.890061099446;8441.113683063313;8458.534780587126;8523.786905327417;8555.144485551395;8623;3259.152620318734;3306.8511916963794;3334.9333013340083;3377.8817741379426;3443.6728510791436;1005.1657124586576;1030.5980667539666;1046.9478174434796;1066.4294602387686;1098.8791232964368;29613;29742.278045115043;29858.267716535433;30062.24844378864;30271.848640756656;916.3310783128354;1050.165118730611;1068.3109394440542;1092.2354351953663;1145.2040831621944;14668.193249371387;15613.3754649815;15662.216889155497;15801.525954221392;16101.919490402473;491012;493787;493917;494530;494823;53750.462495374544;54086.80524310876;54088.45911540913;54204.45795542073;54262.186067209026;1241.74544218435;1251.7496500699897;1330.2816479101177;1336.1114858619103;1338.4846833968797;27308.863455682593;30689;30809.383812324075;30873.382532349675;31433.21416964594;45973;46541.53458465508;46597.3010404851;46610.300845486665;46685;374900;379013;379958;380189;380582;27013.864930676005;29381.412371752016;29758.404831903674;29765.10704678863;29804.254893626967;45078.32382514199;45483.54516454793;45966.31050534311;46994.76502617465;47099;38367.42448863213;39175.21649566936;39640.71974951485;40037.79981100076;40425.39361909515;38173.80913095417;38371.616283837924;38653.420198696476;39349;39956.40065399079;39179.47384735273;39701.20597587974;40155.799221003705;40159.79920100381;40179.196416072096;32910.83544582262;33543.49684754682;34932.82533587316;35520.46719299161;36123.63876361203;27319;27779;27804.44391112126;27842.320126720082;27862.860685696443 22:42:21 INFO - PROCESS | 12161 | |2;jquery.html;378.1567105355056;393.2116107205055;399.07215723443;406.98575549855764;417.40936574746735;13329.333533323332;15302.846971530294;18435.262589496422;18489.90755046223;19238.71141932872;18261.452156435294;18601.441956741284;18802;18838.152283147258;19523.21907123717;5387.19192121181;5420.783168673258;5620.213170156181;5652.971735141339;5658.547316214693;4607.953920460803;5595.272614560122;5645.604807663465;5646.887062258752;5655.915161272585;5431.674099554019;5622.100463925773;5629.437056294379;5647.068233741427;5651.208830763697;5480.287562616854;5517.475839795253;5524.475174858382;5534.252875861747;5587;4793.640476964241;4971.751412429364;4976.104301225778;4985.700857948498;4987.102321582114;21811;22103.557928841496;22467.55064898693;22496;22675.319740407813;2258.3450799268358;2309.261036468331;2342.7071616047992;2607.8174527783062;2662.4009597840522;13720.519781807589;13890.166590004632;13915.721685566232;14604.70790584193;14642.34109465066;1830.597268600919;1876.3432798520546;1885.4321527836553;1897.2695512227754;1958.6768183249746;80909.59545202296;83224;83478.2434790436;83622.58188709017;86708.56645716731;9848.704538863845;10078.092971632588;10096.192304615613;10209.591616335263;10613.20400969915;15062.774058389134;15077.396904123932;15078.396864125312;15100.848991510164;15106.471273505374;7284.890726639104;7595.848083038309;7640.312371886445;7776.416768742254;7783.299503044753;7299.817504562322;7300.525465844703;7475.738349157862;7625.161232264447;7626.923730762733;6144.5084393248435;6255.218097737783;6430.453411460078;6474.902876456857;6746.9325306746305;1733.1334332833583;1775.9378421755177;1792.8834625749284;1821.1076572479656;1828.7348334491423;946.2950102173925;951.2675240065112;972.3388096094582;1006.2704539209014;1061.2412125330425;18443.34009939112;19348;19414;19675.21299148047;19863.702044469344;986.4673076538588;990.960361585543;1024.1601886453036;1024.9795004099876;1085.2999815119363;10386.844197337046;11112.833307500394;11166.274192177483;11208.719782005515;11302.886971130349;461445;467996;472796;473276;480489;49967.50032499701;50193.74903125461;61544.69227653833;77296;79958.20041799507;989.9703008909888;1023.6673081248475;1036.1814166808288;1039.4646756920192;1048.0776916313596;16429.17854107266;16880.240389182763;17434.389796357573;17466.737998929773;17705.645887082443;38351.42472862965;39040.2191956165;39217.80391098026;40225.59774402218;40362.596374035886;50895;52023.31207389506;52653.7367313161;53093.469065308855;53406.46593534015;7681.347085497572;8186.672533098609;8430.283425908867;8734.432261902955;8742.300615950582;12569.560065397667;13149.34253287351;13283.933580332037;13341;13351.666208344868;11510.251833630788;11571.305721656727;11652.883471165518;11767.823482647596;11818.527258909548;11257.437128143723;11466.598669046543;11565.306081634792;11831;11986.700332491757;11652.883471165518;11790.587329443428;11949.22330048544;11949.522019119138;12484.063695223076;7842.725504607311;8118;8128.065272493739;8232.5060496368;8284.212999765012;11134.665960021212;11651.067914566984;11804.586839460577;11835.881641183478;12209.572664956684 22:42:21 INFO - PROCESS | 12161 | |3;mootools.html;1297.7664020476323;1461.3058797071392;1480.9185494797784;1596.6487372777985;1728.2827626534981;6959.234484206733;7686.269804368583;7940.40446966477;7954.005749281347;8189.672413103469;8000.399970002259;8041.638126284317;8055;8056.758297251077;8072.55600941948;2276.840621156524;2306.9077236910503;2307.7692230776956;2310.8151347892212;2318.988405057972;2254.4701995031155;2308.8730119843403;2359.634256690216;2360.5160942006933;2367.147826782357;2247.9887600561974;2251.9774802252014;2276.612975794119;2300.631898896177;2306.2850516339904;2227.5433536125056;2243.7868402501817;2260.683504309398;2271.5684020036224;2273.954520909589;2074.1392322186275;2123.118905654167;2127.372425134589;2131.8294536437047;2148.8495805293637;7911.28798408146;8070.959645201795;8080.353571714308;8101;8105;4992.47579004203;5208.583313334962;5216;5229.50319719626;5231.764570594294;3226.9193270168435;3286.8685252589867;3303.174206448388;3370.3933292007428;3373.8481768320485;1046.204884287939;1085.913126949842;1091.7379828841101;1098.6758906122634;1106.076426184129;10771.83842242367;10777.353358798497;10836.403997780119;10877.238593298393;10878.836817447745;4197.916041679149;4387.407699960465;4470.754108524063;4485.753283569435;4507.842225522091;8857.202851743245;8860;8860.822783544292;8864.113588641085;8871.379003469825;5127.410347809975;5129.692218466904;5137.768800403991;5138.820141295037;5141.820036298712;3958.485396898385;4200.85297014603;4232.195882782262;4320.611144996966;4797.928031079537;3459.135216195951;3509.5262139610827;3589.3180295743723;3611.8735844245853;3680.061584296041;1174.9060075193963;1180.5336891927811;1185.6561597136733;1189.5182451107316;1219.183147291317;950.7860731335533;951.6193522591019;955.6941778630771;963.8120566489467;978.7455261632027;5272.314599102169;5340.198970154508;5468.863278417992;5730.828075157752;5759.740811663485;905.7101727447154;906.8231694819447;907.7186072317471;933.5892207428709;947.3842002698163;3712.7586706863967;3731.1604888900324;3758.8120593970193;3803.8858834235016;3829.4255861620422;9592.23262139041;10031.949840250752;10059.949700251453;10073.294869359075;10082.949585252027;9976.800463990825;10015.649452269136;10036.849447258297;10054.69835904924;10055.39667619945;7108.218096009435;7378.262173782686;7484.1393239777035;7534.434917381109;7729.458937874295;5298.3906850712665;5436.836894893159;5447.918281225864;5540.252065971204;5767.452092051249;9050.185483306534;9082.909170908471;9123.498207598646;9133.817323653622;9153.542322883963;8263.338932885235;9061.95469022677;9397.201237894582;9489.098535639103;9499.952500237454;6608.173978252718;6971.093757811452;7126.821829454305;7135.429165666838;7353.448491363276;7858.60706964638;7859.960700196462;7891.684332626631;7934.722284720238;7991.920080799117;7071.116110486189;7087.255838137127;7132.572045677276;7158.785236442915;7171.426285897011;6748.257691654012;6831.077804496528;6892.655367231719;7067.363937245673;7137.179224389261;6210.068489726396;6311.400416960381;6403.391677790602;6415.839604009937;6429.678516074271;4574.931376029295;4832.589229915497;4853.223484242504;4912.7543622818;5001.974990125026;4822.42130944289;4936.555709986119;4998.875028124326;5065.189569668983;5081.593472522263 22:42:21 INFO - PROCESS | 12161 | |4;prototype.html;329.20332794636977;336.9342978119266;337.65896444590965;337.85809959816885;346.9167399824043;5026.5727413169925;5159.30349402831;5196.558292545134;5207.505286997739;5241;5072.594192464603;5091.465396133399;5099.260607211952;5116.750863201865;5158.7936482540745;4087.57080506547;4092.7339722918064;4093.9181216375655;4100.724098125539;4102.097538541523;3974.2051589682032;4067.0849058961644;4098.774567398792;4102.835886564534;4112.403701463286;4017.8191981360765;4022.6982976276895;4024.859129930453;4042.73722208057;4046.0087278616634;3979.064919743858;3980.681545476355;4008.837437143225;4033.1731994940965;4038.8788336349808;3441.9311613767586;3500.4399296112756;3562.09166662502;3576.7675101118343;3579.2125732339105;4970.453250142481;4996.725180115092;5019.749012549358;5040.6471546991725;5042.193249080129;4603.204750147445;4609.101225261075;4614.423197100363;4624.30635404692;4645.65157613177;5256.715776642024;5344.812931547377;5351.946480535184;5391.218273350381;5414.296141501619;3902.2390633826417;3913.7847418391975;3917.902052448683;3919.157381163035;3919.7648141111627;5522.751476183581;5526.917096243559;5533.22534845122;5539.9169012464445;5562.443755624446;3844.442555829389;3962;3971.947433930011;3979.0450291930306;3987.92024159521;3989.162275922031;4018.517777866675;4026.8187931542566;4031.657309128757;4050.8987275318354;4559;4568.428946381702;4569.223232050559;4588.357629931812;4605.470370907322;5255.5795536357;5354.598405119632;5374.328208973879;5407.405185429599;5409.350877894599;1339.9665008374868;1347.7035052288481;1352.1751731443812;1356.2472827580787;1356.85752995936;888.684516996457;908.8636704494379;918.3938600523622;918.8254231695956;924.4453328003144;5019.924701129486;5025.673331233458;5046.4953504649975;5058.35603428887;5061.164907790247;5277.123967437756;5297.470252974672;5297.54970827484;5307.124324486416;5341.172118321666;4810.735409552508;5021.347224860745;5061.544460998454;5070.7464626768515;5073.594112471067;2817.7464028237555;2821.562657788046;2824.4633519631616;2828.674702409249;2833.761328550422;5476.58925580583;5479.671219726829;5482.205080263419;5486.725663716878;5489.423610520837;5186.325777648882;5328.227407025959;5363.632273770164;5367.543758780547;5400.91898621521;5392.352917649907;5421.783128674967;5485.6982865942755;5504.339479262514;5524;5251.921221181762;5269.420363759907;5279.52484276417;5310.601704872072;5321.928724525664;5174.922376164285;5505.889882202414;5540.36285827135;5577.609567330248;5592.636478628876;5779.0464573344925;5782.971085144715;5797.594168408171;5830.416958304305;5832.9708351457975;3809.561900381381;3885.9417108742828;3917.6474117329576;3923.215356928683;3935.763854168759;5448.945510545002;5480.588955828249;5487;5507.697076660822;5536.833894983157;5387.114509921496;5407.756650950638;5414.10667239901;5421.9186712200135;5438.592105592017;5194.0915834536;5383.838484845461;5416.862458883678;5440.945590544044;5444.8094316698725;5340.172273297645;5349.57203423733;5356.3572371315695;5361;5362.919556206738;5240.292560504283;5434.972825135849;5483.561315094863;5484.698341591251;5495.972520137374;5292.9735351323;5300.920486192787;5305.708186049805;5326.920096198638;5327.9733601331745 22:42:21 INFO - PROCESS | 12161 | |5;yui.html;710.2294010998066;723.6020188896106;728.6903066196865;756.2777547442194;779.1351599724305;13235.272060036707;14216.857831421683;14227.857721422783;14249;14268.571942841705;13080.607581772538;13189.736205275913;13256.46974121034;13297.202167869935;13328.666783330422;656.9014647802844;677.8136012596551;765.47947395771;775.3293401207943;777.9883301750478;757.4962649837851;778.0818634011864;787.5825812319451;789.1201310538737;789.4394979564487;842.2377748137945;844.8690452979798;850.306999795168;852.2542775071812;852.735651947895;691.2948792231896;712.3695529456469;838.3837879158814;842.2209456252972;842.6839935024396;588.2529187931303;606.654207101952;608.4371955940737;608.856918624123;609.5732986909137;12280.078994075391;12332.075094367869;12342.074344424203;12347.814782778265;12358.134930554865;12932.28872412017;13084.672883177902;13254.867451325556;13319.933400332937;13341.799873001914;11592.826107608393;11951.402429878472;12003.639890803202;12062.638120856389;12099.274043557414;4137.958620413818;4175.248455278049;4219;4256.553061928484;4287.8070486828165;57349.713251433895;57901.71049144727;57951.420485795024;58671.706641466946;60757.392426076054;10398.792024159475;10399.48002599867;10516.01007867137;10635.351520514332;10635.52140153695;16926.322947082226;17071.829281707272;17204.91397543004;18266.908665456587;19873.403797886087;7516.624168791539;7636.198199189116;7637.618119094023;7668.846623067508;7689.231076892266;4295.978520107379;4345.543717909574;4345.630621397154;4440.600345968878;4540.977295113569;3086.4135814195233;3161.0990867602623;3163.8892638757534;3165.1295893629435;3167.3506931079073;560.2408736162523;562.9690367029852;566.5156291370901;580.6632153351046;582.659144400522;574.7068994812676;653.6731634182909;674.1842370731372;675.3853992866468;690.3648643248224;8549.145085491402;8688.220624028569;8703.347248956354;8831.381803273838;8895.154960278764;550.3918170421626;574.6638216643227;677.6814896998403;726.0416250549224;726.1794172584945;4187.392828039917;4360.978195109004;4593.517680643551;4658.0036844086635;4683.836065737751;46414.53585464102;51783.48216517862;72568.63715681387;79230;79358.6032069836;67825;68391.65804170947;70051.04566111938;70795;71494.64252678807;58552;60252.39747602556;60434.697826510586;61795.382046178966;63772.68113659309;7983.1617680142745;8097.514149150834;8208.630611622371;8271.379646526415;8382.958085209535;36344.09139771527;38023.61976380201;38993.22013559656;40851.59148408597;43766;39107;39588.20823583454;48877;55351.44648553463;56838.7158064207;11393.316401015965;11853;11899.46452409661;12034.277943323428;12278.201916875374;12979.805302920653;13634.659133521742;13660.86339136596;13674.521391751241;14019.649508762363;12363.93818030904;12548.24423440821;12786.552470663482;13040.54358097462;13238.286181035199;11946.34295113777;12026.518939242333;12180.269183848643;12239.75520489603;12774.936125319315;12082;12164.756704865677;12174.695632609255;12203.511859525875;12288.692782680504;10056.597736090474;10189.643362482277;10288;10335.586576536854;10584.153267738408;10094.596216151273;10107.848382274418;10262.794744105226;10333.638322658671;10503.212259080447 22:42:21 INFO - PROCESS | 12161 | __end_tp_report 22:42:21 INFO - PROCESS | 12161 | __start_cc_report 22:42:21 INFO - PROCESS | 12161 | _x_x_mozilla_cycle_collect,2637 22:42:21 INFO - PROCESS | 12161 | __end_cc_report 22:42:21 INFO - PROCESS | 12161 | __startTimestamp1479192141312__endTimestamp 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | ------- Summary: start ------- 22:42:21 INFO - PROCESS | 12161 | Number of tests: 6 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#0] dojo.html Cycles:165 Average:5251.67 Median:5481.21 stddev:579.26 (10.6%) stddev-sans-first:555.12 22:42:21 INFO - PROCESS | 12161 | Values: 3067.6 3104.0 3129.5 3141.3 3145.4 5166.7 5198.3 5238.5 5301.3 5325.1 5212.7 5214.3 5219.2 5229.5 5232.2 4704.4 4734.2 4746.6 4761.7 4803.3 4854.6 4865.0 4893.1 4894.8 4896.5 4677.0 4714.7 4737.3 4740.2 4862.1 4757.9 4766.6 4791.4 4793.5 4830.2 4685.1 4695.5 4725.6 4733.7 4748.0 5225.5 5227.8 5263.1 5264.5 5320.7 5590.2 5654.1 5656.0 5657.7 5662.0 5505.0 5624.1 5651.1 5720.2 5774.2 5309.1 5312.9 5334.1 5362.6 5366.7 5674.0 5737.5 5756.4 5760.7 5766.6 5386.3 5435.5 5483.0 5493.3 5533.4 5624.6 5647.1 5689.2 5715.2 5766.6 5655.8 5696.3 5703.7 5708.4 5710.3 5504.1 5553.6 5684.8 5750.5 5780.8 4320.5 4368.0 4368.9 4369.4 4423.9 3915.5 3924.8 3930.3 3942.8 3960.0 5386.4 5386.5 5392.0 5400.6 5402.8 5575.2 5652.5 5657.7 5725.7 5734.6 5446.7 5448.1 5449.9 5454.6 5482.6 4950.0 4984.5 4997.5 5001.5 5044.9 5609.8 5609.8 5690.7 5757.3 5926.6 5632.9 5638.2 5646.5 5661.1 5684.0 5794.9 5801.2 5802.5 5804.9 5805.7 5491.9 5523.6 5527.6 5551.8 5551.9 5630.0 5695.0 5696.1 5697.5 5700.9 5485.3 5531.9 5544.7 5546.6 5547.4 5546.6 5551.6 5551.8 5553.1 5554.3 5477.9 5480.9 5481.2 5485.0 5491.8 5515.4 5542.3 5544.6 5545.7 5548.4 5279.7 5365.4 5381.5 5419.5 5484.8 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#1] ext.html Cycles:180 Average:54284.66 Median:38914.32 stddev:107292.16 (275.7%) stddev-sans-first:107517.41 22:42:21 INFO - PROCESS | 12161 | Values: 593.7 606.8 610.5 610.7 611.5 31509.0 40912.4 42231.4 42296.2 45797.8 42502.6 42853.8 42896.6 42924.6 42968.4 8541.5 8683.0 8691.7 8695.6 8699.7 8428.0 8494.4 8499.4 8507.1 8509.8 8551.1 8583.9 8589.9 8595.7 8606.9 8476.2 8477.4 8498.2 8511.3 8526.7 8503.2 8557.1 8569.2 8572.1 8572.2 40251.0 40294.2 40681.6 40727.8 40732.4 3350.0 3375.7 3401.9 3423.4 3454.2 30147.2 30791.7 30915.7 30924.1 31015.4 4027.9 4090.0 4178.9 4200.5 4230.9 321049.0 321680.4 322280.0 323195.0 323417.0 34256.3 34794.5 50072.0 50245.7 54311.5 36471.5 36621.5 36731.4 36825.8 36871.0 13767.0 13961.2 13970.4 13979.9 13982.4 10858.8 10976.6 10983.1 10989.2 10993.9 8441.1 8458.5 8523.8 8555.1 8623.0 3259.2 3306.9 3334.9 3377.9 3443.7 1005.2 1030.6 1046.9 1066.4 1098.9 29613.0 29742.3 29858.3 30062.2 30271.8 916.3 1050.2 1068.3 1092.2 1145.2 14668.2 15613.4 15662.2 15801.5 16101.9 491012.0 493787.0 493917.0 494530.0 494823.0 53750.5 54086.8 54088.5 54204.5 54262.2 1241.7 1251.7 1330.3 1336.1 1338.5 27308.9 30689.0 30809.4 30873.4 31433.2 45973.0 46541.5 46597.3 46610.3 46685.0 374900.0 379013.0 379958.0 380189.0 380582.0 27013.9 29381.4 29758.4 29765.1 29804.3 45078.3 45483.5 45966.3 46994.8 47099.0 38367.4 39175.2 39640.7 40037.8 40425.4 38173.8 38371.6 38653.4 39349.0 39956.4 39179.5 39701.2 40155.8 40159.8 40179.2 32910.8 33543.5 34932.8 35520.5 36123.6 27319.0 27779.0 27804.4 27842.3 27862.9 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#2] jquery.html Cycles:180 Average:27504.74 Median:2475.26 stddev:77317.18 (3123.6%) stddev-sans-first:77507.25 22:42:21 INFO - PROCESS | 12161 | Values: 378.2 393.2 399.1 407.0 417.4 13329.3 15302.8 18435.3 18489.9 19238.7 18261.5 18601.4 18802.0 18838.2 19523.2 5387.2 5420.8 5620.2 5653.0 5658.5 4608.0 5595.3 5645.6 5646.9 5655.9 5431.7 5622.1 5629.4 5647.1 5651.2 5480.3 5517.5 5524.5 5534.3 5587.0 4793.6 4971.8 4976.1 4985.7 4987.1 21811.0 22103.6 22467.6 22496.0 22675.3 2258.3 2309.3 2342.7 2607.8 2662.4 13720.5 13890.2 13915.7 14604.7 14642.3 1830.6 1876.3 1885.4 1897.3 1958.7 80909.6 83224.0 83478.2 83622.6 86708.6 9848.7 10078.1 10096.2 10209.6 10613.2 15062.8 15077.4 15078.4 15100.8 15106.5 7284.9 7595.8 7640.3 7776.4 7783.3 7299.8 7300.5 7475.7 7625.2 7626.9 6144.5 6255.2 6430.5 6474.9 6746.9 1733.1 1775.9 1792.9 1821.1 1828.7 946.3 951.3 972.3 1006.3 1061.2 18443.3 19348.0 19414.0 19675.2 19863.7 986.5 991.0 1024.2 1025.0 1085.3 10386.8 11112.8 11166.3 11208.7 11302.9 461445.0 467996.0 472796.0 473276.0 480489.0 49967.5 50193.7 61544.7 77296.0 79958.2 990.0 1023.7 1036.2 1039.5 1048.1 16429.2 16880.2 17434.4 17466.7 17705.6 38351.4 39040.2 39217.8 40225.6 40362.6 50895.0 52023.3 52653.7 53093.5 53406.5 7681.3 8186.7 8430.3 8734.4 8742.3 12569.6 13149.3 13283.9 13341.0 13351.7 11510.3 11571.3 11652.9 11767.8 11818.5 11257.4 11466.6 11565.3 11831.0 11986.7 11652.9 11790.6 11949.2 11949.5 12484.1 7842.7 8118.0 8128.1 8232.5 8284.2 11134.7 11651.1 11804.6 11835.9 12209.6 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#3] mootools.html Cycles:180 Average:5334.65 Median:5133.73 stddev:2918.48 (56.8%) stddev-sans-first:2910.89 22:42:21 INFO - PROCESS | 12161 | Values: 1297.8 1461.3 1480.9 1596.6 1728.3 6959.2 7686.3 7940.4 7954.0 8189.7 8000.4 8041.6 8055.0 8056.8 8072.6 2276.8 2306.9 2307.8 2310.8 2319.0 2254.5 2308.9 2359.6 2360.5 2367.1 2248.0 2252.0 2276.6 2300.6 2306.3 2227.5 2243.8 2260.7 2271.6 2274.0 2074.1 2123.1 2127.4 2131.8 2148.8 7911.3 8071.0 8080.4 8101.0 8105.0 4992.5 5208.6 5216.0 5229.5 5231.8 3226.9 3286.9 3303.2 3370.4 3373.8 1046.2 1085.9 1091.7 1098.7 1106.1 10771.8 10777.4 10836.4 10877.2 10878.8 4197.9 4387.4 4470.8 4485.8 4507.8 8857.2 8860.0 8860.8 8864.1 8871.4 5127.4 5129.7 5137.8 5138.8 5141.8 3958.5 4200.9 4232.2 4320.6 4797.9 3459.1 3509.5 3589.3 3611.9 3680.1 1174.9 1180.5 1185.7 1189.5 1219.2 950.8 951.6 955.7 963.8 978.7 5272.3 5340.2 5468.9 5730.8 5759.7 905.7 906.8 907.7 933.6 947.4 3712.8 3731.2 3758.8 3803.9 3829.4 9592.2 10031.9 10059.9 10073.3 10082.9 9976.8 10015.6 10036.8 10054.7 10055.4 7108.2 7378.3 7484.1 7534.4 7729.5 5298.4 5436.8 5447.9 5540.3 5767.5 9050.2 9082.9 9123.5 9133.8 9153.5 8263.3 9062.0 9397.2 9489.1 9500.0 6608.2 6971.1 7126.8 7135.4 7353.4 7858.6 7860.0 7891.7 7934.7 7991.9 7071.1 7087.3 7132.6 7158.8 7171.4 6748.3 6831.1 6892.7 7067.4 7137.2 6210.1 6311.4 6403.4 6415.8 6429.7 4574.9 4832.6 4853.2 4912.8 5002.0 4822.4 4936.6 4998.9 5065.2 5081.6 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#4] prototype.html Cycles:180 Average:4507.09 Median:5159.05 stddev:1311.04 (25.4%) stddev-sans-first:1276.66 22:42:21 INFO - PROCESS | 12161 | Values: 329.2 336.9 337.7 337.9 346.9 5026.6 5159.3 5196.6 5207.5 5241.0 5072.6 5091.5 5099.3 5116.8 5158.8 4087.6 4092.7 4093.9 4100.7 4102.1 3974.2 4067.1 4098.8 4102.8 4112.4 4017.8 4022.7 4024.9 4042.7 4046.0 3979.1 3980.7 4008.8 4033.2 4038.9 3441.9 3500.4 3562.1 3576.8 3579.2 4970.5 4996.7 5019.7 5040.6 5042.2 4603.2 4609.1 4614.4 4624.3 4645.7 5256.7 5344.8 5351.9 5391.2 5414.3 3902.2 3913.8 3917.9 3919.2 3919.8 5522.8 5526.9 5533.2 5539.9 5562.4 3844.4 3962.0 3971.9 3979.0 3987.9 3989.2 4018.5 4026.8 4031.7 4050.9 4559.0 4568.4 4569.2 4588.4 4605.5 5255.6 5354.6 5374.3 5407.4 5409.4 1340.0 1347.7 1352.2 1356.2 1356.9 888.7 908.9 918.4 918.8 924.4 5019.9 5025.7 5046.5 5058.4 5061.2 5277.1 5297.5 5297.5 5307.1 5341.2 4810.7 5021.3 5061.5 5070.7 5073.6 2817.7 2821.6 2824.5 2828.7 2833.8 5476.6 5479.7 5482.2 5486.7 5489.4 5186.3 5328.2 5363.6 5367.5 5400.9 5392.4 5421.8 5485.7 5504.3 5524.0 5251.9 5269.4 5279.5 5310.6 5321.9 5174.9 5505.9 5540.4 5577.6 5592.6 5779.0 5783.0 5797.6 5830.4 5833.0 3809.6 3885.9 3917.6 3923.2 3935.8 5448.9 5480.6 5487.0 5507.7 5536.8 5387.1 5407.8 5414.1 5421.9 5438.6 5194.1 5383.8 5416.9 5440.9 5444.8 5340.2 5349.6 5356.4 5361.0 5362.9 5240.3 5435.0 5483.6 5484.7 5496.0 5293.0 5300.9 5305.7 5326.9 5328.0 22:42:21 INFO - PROCESS | 12161 | 22:42:21 INFO - PROCESS | 12161 | [#5] yui.html Cycles:180 Average:15726.50 Median:24063.49 stddev:19856.32 (82.5%) stddev-sans-first:19880.00 22:42:21 INFO - PROCESS | 12161 | Values: 710.2 723.6 728.7 756.3 779.1 13235.3 14216.9 14227.9 14249.0 14268.6 13080.6 13189.7 13256.5 13297.2 13328.7 656.9 677.8 765.5 775.3 778.0 757.5 778.1 787.6 789.1 789.4 842.2 844.9 850.3 852.3 852.7 691.3 712.4 838.4 842.2 842.7 588.3 606.7 608.4 608.9 609.6 12280.1 12332.1 12342.1 12347.8 12358.1 12932.3 13084.7 13254.9 13319.9 13341.8 11592.8 11951.4 12003.6 12062.6 12099.3 4138.0 4175.2 4219.0 4256.6 4287.8 57349.7 57901.7 57951.4 58671.7 60757.4 10398.8 10399.5 10516.0 10635.4 10635.5 16926.3 17071.8 17204.9 18266.9 19873.4 7516.6 7636.2 7637.6 7668.8 7689.2 4296.0 4345.5 4345.6 4440.6 4541.0 3086.4 3161.1 3163.9 3165.1 3167.4 560.2 563.0 566.5 580.7 582.7 574.7 653.7 674.2 675.4 690.4 8549.1 8688.2 8703.3 8831.4 8895.2 550.4 574.7 677.7 726.0 726.2 4187.4 4361.0 4593.5 4658.0 4683.8 46414.5 51783.5 72568.6 79230.0 79358.6 67825.0 68391.7 70051.0 70795.0 71494.6 58552.0 60252.4 60434.7 61795.4 63772.7 7983.2 8097.5 8208.6 8271.4 8383.0 36344.1 38023.6 38993.2 40851.6 43766.0 39107.0 39588.2 48877.0 55351.4 56838.7 11393.3 11853.0 11899.5 12034.3 12278.2 12979.8 13634.7 13660.9 13674.5 14019.6 12363.9 12548.2 12786.6 13040.5 13238.3 11946.3 12026.5 12180.3 12239.8 12774.9 12082.0 12164.8 12174.7 12203.5 12288.7 10056.6 10189.6 10288.0 10335.6 10584.2 10094.6 10107.8 10262.8 10333.6 10503.2 22:42:21 INFO - PROCESS | 12161 | -------- Summary: end -------- 22:42:21 INFO - PROCESS | 12161 | 22:42:22 INFO - TEST-INFO | 12161: exit 0 22:42:22 INFO - TEST-OK | dromaeo_css | took 1117506ms 22:42:22 INFO - TEST-START | kraken 22:42:22 INFO - operating with platform_type : linux_ 22:42:22 INFO - Initialising browser for kraken test... 22:42:22 INFO - TEST-INFO | started process 12473 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsgGCnV/profile http://localhost:47371/getInfo.html) 22:42:25 INFO - PROCESS | 12473 | __metrics Screen width/height:1600/1200 22:42:25 INFO - PROCESS | 12473 | colorDepth:24 22:42:25 INFO - PROCESS | 12473 | Browser inner width/height: 1024/697 22:42:25 INFO - PROCESS | 12473 | __metrics 22:42:26 INFO - PROCESS | 12473 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:42:26 INFO - PROCESS | 12473 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:42:26 INFO - PROCESS | 12473 | console.error: PushService: 22:42:26 INFO - PROCESS | 12473 | stateChangeProcessEnqueue: Error transitioning state 22:42:26 INFO - PROCESS | 12473 | UnknownError 22:42:27 INFO - TEST-INFO | 12473: exit 0 22:42:27 INFO - Browser initialized. 22:42:27 INFO - Running cycle 1/1 for kraken test... 22:42:27 INFO - TEST-INFO | started process 12557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsgGCnV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:42:39 INFO - PROCESS | 12557 | RSS: Main: 173584384 22:42:39 INFO - PROCESS | 12557 | 22:42:42 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47371/tests/kraken/driver.html?testName=audio-beat-detection) 22:42:43 INFO - PROCESS | 12557 | RSS: Main: 234729472 22:42:43 INFO - PROCESS | 12557 | 22:42:45 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47371/tests/kraken/driver.html?testName=audio-dft) 22:42:46 INFO - PROCESS | 12557 | RSS: Main: 216887296 22:42:46 INFO - PROCESS | 12557 | 22:42:49 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47371/tests/kraken/driver.html?testName=audio-fft) 22:42:50 INFO - PROCESS | 12557 | RSS: Main: 297271296 22:42:50 INFO - PROCESS | 12557 | 22:42:52 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47371/tests/kraken/driver.html?testName=audio-oscillator) 22:42:52 INFO - PROCESS | 12557 | RSS: Main: 209412096 22:42:52 INFO - PROCESS | 12557 | 22:42:54 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47371/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:42:55 INFO - PROCESS | 12557 | RSS: Main: 212668416 22:42:55 INFO - PROCESS | 12557 | 22:42:58 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47371/tests/kraken/driver.html?testName=imaging-darkroom) 22:42:59 INFO - PROCESS | 12557 | RSS: Main: 253386752 22:42:59 INFO - PROCESS | 12557 | 22:43:02 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47371/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:03 INFO - PROCESS | 12557 | RSS: Main: 252174336 22:43:03 INFO - PROCESS | 12557 | 22:43:05 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47371/tests/kraken/driver.html?testName=json-parse-financial) 22:43:06 INFO - PROCESS | 12557 | RSS: Main: 250421248 22:43:06 INFO - PROCESS | 12557 | 22:43:07 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47371/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:43:08 INFO - PROCESS | 12557 | RSS: Main: 213446656 22:43:08 INFO - PROCESS | 12557 | 22:43:10 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:43:10 INFO - PROCESS | 12557 | RSS: Main: 219058176 22:43:10 INFO - PROCESS | 12557 | 22:43:12 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:43:13 INFO - PROCESS | 12557 | RSS: Main: 228184064 22:43:13 INFO - PROCESS | 12557 | 22:43:16 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:43:16 INFO - PROCESS | 12557 | RSS: Main: 237215744 22:43:16 INFO - PROCESS | 12557 | 22:43:19 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:43:20 INFO - PROCESS | 12557 | RSS: Main: 234242048 22:43:20 INFO - PROCESS | 12557 | 22:43:21 INFO - PROCESS | 12557 | Cycle 1(1): loaded http://localhost:47371/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47371/tests/kraken/driver.html?testName=ai-astar) 22:43:22 INFO - PROCESS | 12557 | RSS: Main: 286265344 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | __start_tp_report 22:43:22 INFO - PROCESS | 12557 | _x_x_mozilla_page_load 22:43:22 INFO - PROCESS | 12557 | _x_x_mozilla_page_load_details 22:43:22 INFO - PROCESS | 12557 | |i|pagename|runs| 22:43:22 INFO - PROCESS | 12557 | |0;ai-astar;112;112;112;115;113;113;114;113;112;114 22:43:22 INFO - PROCESS | 12557 | |1;audio-beat-detection;151;120;122;162;123;126;122;121;155;123 22:43:22 INFO - PROCESS | 12557 | |2;audio-dft;160;162;161;159;160;161;160;161;162;161 22:43:22 INFO - PROCESS | 12557 | |3;audio-fft;74;74;77;106;76;76;76;75;101;76 22:43:22 INFO - PROCESS | 12557 | |4;audio-oscillator;82;83;112;84;83;83;107;83;83;122 22:43:22 INFO - PROCESS | 12557 | |5;imaging-gaussian-blur;117;117;117;117;117;118;117;117;117;117 22:43:22 INFO - PROCESS | 12557 | |6;imaging-darkroom;147;146;147;147;146;147;146;148;147;147 22:43:22 INFO - PROCESS | 12557 | |7;imaging-desaturate;84;84;85;85;85;84;85;84;84;84 22:43:22 INFO - PROCESS | 12557 | |8;json-parse-financial;53;51;53;52;53;54;53;53;54;53 22:43:22 INFO - PROCESS | 12557 | |9;json-stringify-tinderbox;63;62;62;63;62;62;62;64;63;62 22:43:22 INFO - PROCESS | 12557 | |10;stanford-crypto-aes;79;81;88;80;82;87;81;80;79;87 22:43:22 INFO - PROCESS | 12557 | |11;stanford-crypto-ccm;137;143;142;130;137;134;140;139;177;142 22:43:22 INFO - PROCESS | 12557 | |12;stanford-crypto-pbkdf2;171;165;166;165;166;168;171;170;166;165 22:43:22 INFO - PROCESS | 12557 | |13;stanford-crypto-sha256-iterative;65;65;68;70;65;64;64;65;63;64 22:43:22 INFO - PROCESS | 12557 | __end_tp_report 22:43:22 INFO - PROCESS | 12557 | __start_cc_report 22:43:22 INFO - PROCESS | 12557 | _x_x_mozilla_cycle_collect,1676 22:43:22 INFO - PROCESS | 12557 | __end_cc_report 22:43:22 INFO - PROCESS | 12557 | __startTimestamp1479192202214__endTimestamp 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | ------- Summary: start ------- 22:43:22 INFO - PROCESS | 12557 | Number of tests: 14 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:1.05 (0.9%) stddev-sans-first:1.05 22:43:22 INFO - PROCESS | 12557 | Values: 112.0 112.0 112.0 115.0 113.0 113.0 114.0 113.0 112.0 114.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:124.50 stddev:16.50 (13.3%) stddev-sans-first:16.09 22:43:22 INFO - PROCESS | 12557 | Values: 151.0 120.0 122.0 162.0 123.0 126.0 122.0 121.0 155.0 123.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 22:43:22 INFO - PROCESS | 12557 | Values: 160.0 162.0 161.0 159.0 160.0 161.0 160.0 161.0 162.0 161.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:11.90 (15.7%) stddev-sans-first:12.34 22:43:22 INFO - PROCESS | 12557 | Values: 74.0 74.0 77.0 106.0 76.0 76.0 76.0 75.0 101.0 76.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:83.00 stddev:15.25 (18.4%) stddev-sans-first:15.72 22:43:22 INFO - PROCESS | 12557 | Values: 82.0 83.0 112.0 84.0 83.0 83.0 107.0 83.0 83.0 122.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:43:22 INFO - PROCESS | 12557 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:43:22 INFO - PROCESS | 12557 | Values: 147.0 146.0 147.0 147.0 146.0 147.0 146.0 148.0 147.0 147.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 22:43:22 INFO - PROCESS | 12557 | Values: 84.0 84.0 85.0 85.0 85.0 84.0 85.0 84.0 84.0 84.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.93 22:43:22 INFO - PROCESS | 12557 | Values: 53.0 51.0 53.0 52.0 53.0 54.0 53.0 53.0 54.0 53.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:62.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 22:43:22 INFO - PROCESS | 12557 | Values: 63.0 62.0 62.0 63.0 62.0 62.0 62.0 64.0 63.0 62.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:81.50 stddev:3.53 (4.3%) stddev-sans-first:3.53 22:43:22 INFO - PROCESS | 12557 | Values: 79.0 81.0 88.0 80.0 82.0 87.0 81.0 80.0 79.0 87.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:141.00 stddev:12.90 (9.1%) stddev-sans-first:13.55 22:43:22 INFO - PROCESS | 12557 | Values: 137.0 143.0 142.0 130.0 137.0 134.0 140.0 139.0 177.0 142.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.00 stddev:2.50 (1.5%) stddev-sans-first:2.26 22:43:22 INFO - PROCESS | 12557 | Values: 171.0 165.0 166.0 165.0 166.0 168.0 171.0 170.0 166.0 165.0 22:43:22 INFO - PROCESS | 12557 | 22:43:22 INFO - PROCESS | 12557 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:2.11 (3.2%) stddev-sans-first:2.24 22:43:22 INFO - PROCESS | 12557 | Values: 65.0 65.0 68.0 70.0 65.0 64.0 64.0 65.0 63.0 64.0 22:43:22 INFO - PROCESS | 12557 | -------- Summary: end -------- 22:43:22 INFO - PROCESS | 12557 | 22:43:23 INFO - TEST-INFO | 12557: exit 0 22:43:23 INFO - TEST-OK | kraken | took 61231ms 22:43:23 INFO - SUITE-END | took 1178s 22:43:23 INFO - Completed test suite (00:19:39) 22:43:23 INFO - javascript benchmark 22:43:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3067.5552044953483, 3103.953440698391, 3129.499280115182, 3141.293209027969, 3145.4180976519347, 5166.741662916858, 5198.324217851674, 5238.502342277482, 5301.310829592152, 5325.147976323789, 5212.66117702349, 5214.296070030545, 5219.217117432387, 5229.450907654698, 5232.16285394337, 4704.364910737049, 4734.195186818232, 4746.620270378378, 4761.666683332168, 4803.327534145223, 4854.58736007438, 4864.975675121619, 4893.070316639845, 4894.8286809961655, 4896.534829191221, 4676.953230467686, 4714.740689262089, 4737.289406589005, 4740.217864052444, 4862.100511405383, 4757.9286310705365, 4766.642501812378, 4791.3531673224015, 4793.496682848286, 4830.2030165022725, 4685.1332503486965, 4695.506971767949, 4725.598324142452, 4733.739644319561, 4748.0, 5225.529702326809, 5227.843164705065, 5263.0526505229045, 5264.473552644743, 5320.707361095138, 5590.161475778666, 5654.123610840326, 5656.0, 5657.717114144318, 5661.971690141564, 5505.0, 5624.100143976944, 5651.095824668075, 5720.170575266605, 5774.16274640179, 5309.070912590281, 5312.867178320573, 5334.0665383557725, 5362.570994320445, 5366.677999319975, 5673.971630141823, 5737.512311453574, 5756.424357564294, 5760.711964401763, 5766.625169363978, 5386.272853164851, 5435.537979271727, 5483.0, 5493.340799104133, 5533.446655334435, 5624.55003599711, 5647.124695672177, 5689.231953686282, 5715.1713001614535, 5766.596338256281, 5655.830325090254, 5696.3164420269, 5703.686297253608, 5708.400617935057, 5710.314762228559, 5504.119340905436, 5553.639013464192, 5684.772609095672, 5750.539956803445, 5780.797672081457, 4320.546342634041, 4368.0, 4368.912621747547, 4369.366441865912, 4423.911521769611, 3915.451836742858, 3924.781639331247, 3930.331843586597, 3942.842286308573, 3959.9604003959594, 5386.380566234855, 5386.542143917811, 5391.973040134852, 5400.594955378284, 5402.783888644489, 5575.191597218381, 5652.547796176296, 5657.745401456862, 5725.713714314351, 5734.598578099576, 5446.7276636168035, 5448.101063324586, 5449.863753406117, 5454.563634909197, 5482.643628164156, 4949.975250123726, 4984.501549844987, 4997.525235102659, 5001.474845141208, 5044.924326135111, 5609.775608975677, 5609.80365687199, 5690.658560486384, 5757.337906140848, 5926.644401335933, 5632.85917852057, 5638.21065050885, 5646.520045795989, 5661.122526008476, 5684.0, 5794.942050579609, 5801.245838041028, 5802.477776999922, 5804.912926306024, 5805.680687562224, 5491.890162196813, 5523.558115350843, 5527.613067085265, 5551.833444996495, 5551.944480555304, 5630.042892708331, 5695.0, 5696.088625819849, 5697.4587414195585, 5700.857478563069, 5485.259489968803, 5531.8893622128135, 5544.667319960815, 5546.556275498031, 5547.44525547442, 5546.639468434701, 5551.611387202857, 5551.805686800943, 5553.0837411828215, 5554.250176226158, 5477.863053423696, 5480.890382192413, 5481.205225242238, 5485.01269771445, 5491.807786727445, 5515.365732940763, 5542.2795036644975, 5544.584156188222, 5545.722713864371, 5548.445155484581, 5279.656822306538, 5365.356157261153, 5381.542568881689, 5419.485148910847, 5484.753186106715], "value": 5213.906113826229, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.661612880658, 606.766394937949, 610.4627927423863, 610.6946526736631, 611.4955161991359, 31509.0, 40912.38631420531, 42231.366529502004, 42296.17520758658, 45797.77101114498, 42502.574974250245, 42853.78573107138, 42896.57103428965, 42924.57075429245, 42968.35547466783, 8541.487510749344, 8683.044865064869, 8691.739247822576, 8695.608697608592, 8699.739007829776, 8428.030776460693, 8494.447860889053, 8499.405041647087, 8507.149285071506, 8509.787255318635, 8551.102134275905, 8583.914160858405, 8589.871151932726, 8595.699150529732, 8606.913930860674, 8476.194761497647, 8477.406581539295, 8498.235158835703, 8511.27654149398, 8526.658933642648, 8503.23470887623, 8557.10150434208, 8569.22876941072, 8572.099929507374, 8572.228499435018, 40251.0, 40294.194116117804, 40681.59318406808, 40727.7963610183, 40732.38901416452, 3350.011746534754, 3375.729941604666, 3401.914952126192, 3423.400904841643, 3454.24006718524, 30147.246318841986, 30791.692083079106, 30915.690843091732, 30924.144551326994, 31015.379692406026, 4027.8993025174314, 4089.9591004089875, 4178.874633760992, 4200.474940632421, 4230.894227644303, 321049.0, 321680.3915980405, 322279.9999999977, 323195.0, 323417.0, 34256.31487370239, 34794.47808282878, 50072.0, 50245.74877125664, 54311.45688543143, 36471.4529282061, 36621.450678239846, 36731.4490282646, 36825.81587092047, 36871.0, 13767.0, 13961.162330260217, 13970.441182352592, 13979.930100349433, 13982.370793314325, 10858.782824343627, 10976.560937562568, 10983.121350291958, 10989.175811813986, 10993.890061099446, 8441.113683063313, 8458.534780587126, 8523.786905327417, 8555.144485551395, 8623.0, 3259.152620318734, 3306.8511916963794, 3334.9333013340083, 3377.8817741379426, 3443.6728510791436, 1005.1657124586576, 1030.5980667539666, 1046.9478174434796, 1066.4294602387686, 1098.8791232964368, 29613.0, 29742.278045115043, 29858.267716535433, 30062.24844378864, 30271.848640756656, 916.3310783128354, 1050.165118730611, 1068.3109394440542, 1092.2354351953663, 1145.2040831621944, 14668.193249371387, 15613.3754649815, 15662.216889155497, 15801.525954221392, 16101.919490402473, 491012.0, 493787.0, 493917.0, 494530.0, 494823.0, 53750.462495374544, 54086.80524310876, 54088.45911540913, 54204.45795542073, 54262.186067209026, 1241.74544218435, 1251.7496500699897, 1330.2816479101177, 1336.1114858619103, 1338.4846833968797, 27308.863455682593, 30689.0, 30809.383812324075, 30873.382532349675, 31433.21416964594, 45973.0, 46541.53458465508, 46597.3010404851, 46610.300845486665, 46685.0, 374900.0, 379013.0, 379958.0, 380189.0, 380582.0, 27013.864930676005, 29381.412371752016, 29758.404831903674, 29765.10704678863, 29804.254893626967, 45078.32382514199, 45483.54516454793, 45966.31050534311, 46994.76502617465, 47099.0, 38367.42448863213, 39175.21649566936, 39640.71974951485, 40037.79981100076, 40425.39361909515, 38173.80913095417, 38371.616283837924, 38653.420198696476, 39349.0, 39956.40065399079, 39179.47384735273, 39701.20597587974, 40155.799221003705, 40159.79920100381, 40179.196416072096, 32910.83544582262, 33543.49684754682, 34932.82533587316, 35520.46719299161, 36123.63876361203, 27319.0, 27779.0, 27804.44391112126, 27842.320126720082, 27862.860685696443], "value": 17977.486516520767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.1567105355056, 393.2116107205055, 399.07215723443, 406.98575549855764, 417.40936574746735, 13329.333533323332, 15302.846971530294, 18435.262589496422, 18489.90755046223, 19238.71141932872, 18261.452156435294, 18601.441956741284, 18802.0, 18838.152283147258, 19523.21907123717, 5387.19192121181, 5420.783168673258, 5620.213170156181, 5652.971735141339, 5658.547316214693, 4607.953920460803, 5595.272614560122, 5645.604807663465, 5646.887062258752, 5655.915161272585, 5431.674099554019, 5622.100463925773, 5629.437056294379, 5647.068233741427, 5651.208830763697, 5480.287562616854, 5517.475839795253, 5524.475174858382, 5534.252875861747, 5587.0, 4793.640476964241, 4971.751412429364, 4976.104301225778, 4985.700857948498, 4987.102321582114, 21811.0, 22103.557928841496, 22467.55064898693, 22496.0, 22675.319740407813, 2258.3450799268358, 2309.261036468331, 2342.7071616047992, 2607.8174527783062, 2662.4009597840522, 13720.519781807589, 13890.166590004632, 13915.721685566232, 14604.70790584193, 14642.34109465066, 1830.597268600919, 1876.3432798520546, 1885.4321527836553, 1897.2695512227754, 1958.6768183249746, 80909.59545202296, 83224.0, 83478.2434790436, 83622.58188709017, 86708.56645716731, 9848.704538863845, 10078.092971632588, 10096.192304615613, 10209.591616335263, 10613.20400969915, 15062.774058389134, 15077.396904123932, 15078.396864125312, 15100.848991510164, 15106.471273505374, 7284.890726639104, 7595.848083038309, 7640.312371886445, 7776.416768742254, 7783.299503044753, 7299.817504562322, 7300.525465844703, 7475.738349157862, 7625.161232264447, 7626.923730762733, 6144.5084393248435, 6255.218097737783, 6430.453411460078, 6474.902876456857, 6746.9325306746305, 1733.1334332833583, 1775.9378421755177, 1792.8834625749284, 1821.1076572479656, 1828.7348334491423, 946.2950102173925, 951.2675240065112, 972.3388096094582, 1006.2704539209014, 1061.2412125330425, 18443.34009939112, 19348.0, 19414.0, 19675.21299148047, 19863.702044469344, 986.4673076538588, 990.960361585543, 1024.1601886453036, 1024.9795004099876, 1085.2999815119363, 10386.844197337046, 11112.833307500394, 11166.274192177483, 11208.719782005515, 11302.886971130349, 461445.0, 467996.0, 472796.0, 473276.0, 480489.0, 49967.50032499701, 50193.74903125461, 61544.69227653833, 77296.0, 79958.20041799507, 989.9703008909888, 1023.6673081248475, 1036.1814166808288, 1039.4646756920192, 1048.0776916313596, 16429.17854107266, 16880.240389182763, 17434.389796357573, 17466.737998929773, 17705.645887082443, 38351.42472862965, 39040.2191956165, 39217.80391098026, 40225.59774402218, 40362.596374035886, 50895.0, 52023.31207389506, 52653.7367313161, 53093.469065308855, 53406.46593534015, 7681.347085497572, 8186.672533098609, 8430.283425908867, 8734.432261902955, 8742.300615950582, 12569.560065397667, 13149.34253287351, 13283.933580332037, 13341.0, 13351.666208344868, 11510.251833630788, 11571.305721656727, 11652.883471165518, 11767.823482647596, 11818.527258909548, 11257.437128143723, 11466.598669046543, 11565.306081634792, 11831.0, 11986.700332491757, 11652.883471165518, 11790.587329443428, 11949.22330048544, 11949.522019119138, 12484.063695223076, 7842.725504607311, 8118.0, 8128.065272493739, 8232.5060496368, 8284.212999765012, 11134.665960021212, 11651.067914566984, 11804.586839460577, 11835.881641183478, 12209.572664956684], "value": 9061.275991411023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.7664020476323, 1461.3058797071392, 1480.9185494797784, 1596.6487372777985, 1728.2827626534981, 6959.234484206733, 7686.269804368583, 7940.40446966477, 7954.005749281347, 8189.672413103469, 8000.399970002259, 8041.638126284317, 8055.0, 8056.758297251077, 8072.55600941948, 2276.840621156524, 2306.9077236910503, 2307.7692230776956, 2310.8151347892212, 2318.988405057972, 2254.4701995031155, 2308.8730119843403, 2359.634256690216, 2360.5160942006933, 2367.147826782357, 2247.9887600561974, 2251.9774802252014, 2276.612975794119, 2300.631898896177, 2306.2850516339904, 2227.5433536125056, 2243.7868402501817, 2260.683504309398, 2271.5684020036224, 2273.954520909589, 2074.1392322186275, 2123.118905654167, 2127.372425134589, 2131.8294536437047, 2148.8495805293637, 7911.28798408146, 8070.959645201795, 8080.353571714308, 8101.0, 8105.0, 4992.47579004203, 5208.583313334962, 5216.0, 5229.50319719626, 5231.764570594294, 3226.9193270168435, 3286.8685252589867, 3303.174206448388, 3370.3933292007428, 3373.8481768320485, 1046.204884287939, 1085.913126949842, 1091.7379828841101, 1098.6758906122634, 1106.076426184129, 10771.83842242367, 10777.353358798497, 10836.403997780119, 10877.238593298393, 10878.836817447745, 4197.916041679149, 4387.407699960465, 4470.754108524063, 4485.753283569435, 4507.842225522091, 8857.202851743245, 8860.0, 8860.822783544292, 8864.113588641085, 8871.379003469825, 5127.410347809975, 5129.692218466904, 5137.768800403991, 5138.820141295037, 5141.820036298712, 3958.485396898385, 4200.85297014603, 4232.195882782262, 4320.611144996966, 4797.928031079537, 3459.135216195951, 3509.5262139610827, 3589.3180295743723, 3611.8735844245853, 3680.061584296041, 1174.9060075193963, 1180.5336891927811, 1185.6561597136733, 1189.5182451107316, 1219.183147291317, 950.7860731335533, 951.6193522591019, 955.6941778630771, 963.8120566489467, 978.7455261632027, 5272.314599102169, 5340.198970154508, 5468.863278417992, 5730.828075157752, 5759.740811663485, 905.7101727447154, 906.8231694819447, 907.7186072317471, 933.5892207428709, 947.3842002698163, 3712.7586706863967, 3731.1604888900324, 3758.8120593970193, 3803.8858834235016, 3829.4255861620422, 9592.23262139041, 10031.949840250752, 10059.949700251453, 10073.294869359075, 10082.949585252027, 9976.800463990825, 10015.649452269136, 10036.849447258297, 10054.69835904924, 10055.39667619945, 7108.218096009435, 7378.262173782686, 7484.1393239777035, 7534.434917381109, 7729.458937874295, 5298.3906850712665, 5436.836894893159, 5447.918281225864, 5540.252065971204, 5767.452092051249, 9050.185483306534, 9082.909170908471, 9123.498207598646, 9133.817323653622, 9153.542322883963, 8263.338932885235, 9061.95469022677, 9397.201237894582, 9489.098535639103, 9499.952500237454, 6608.173978252718, 6971.093757811452, 7126.821829454305, 7135.429165666838, 7353.448491363276, 7858.60706964638, 7859.960700196462, 7891.684332626631, 7934.722284720238, 7991.920080799117, 7071.116110486189, 7087.255838137127, 7132.572045677276, 7158.785236442915, 7171.426285897011, 6748.257691654012, 6831.077804496528, 6892.655367231719, 7067.363937245673, 7137.179224389261, 6210.068489726396, 6311.400416960381, 6403.391677790602, 6415.839604009937, 6429.678516074271, 4574.931376029295, 4832.589229915497, 4853.223484242504, 4912.7543622818, 5001.974990125026, 4822.42130944289, 4936.555709986119, 4998.875028124326, 5065.189569668983, 5081.593472522263], "value": 4336.730632826802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.20332794636977, 336.9342978119266, 337.65896444590965, 337.85809959816885, 346.9167399824043, 5026.5727413169925, 5159.30349402831, 5196.558292545134, 5207.505286997739, 5241.0, 5072.594192464603, 5091.465396133399, 5099.260607211952, 5116.750863201865, 5158.7936482540745, 4087.57080506547, 4092.7339722918064, 4093.9181216375655, 4100.724098125539, 4102.097538541523, 3974.2051589682032, 4067.0849058961644, 4098.774567398792, 4102.835886564534, 4112.403701463286, 4017.8191981360765, 4022.6982976276895, 4024.859129930453, 4042.73722208057, 4046.0087278616634, 3979.064919743858, 3980.681545476355, 4008.837437143225, 4033.1731994940965, 4038.8788336349808, 3441.9311613767586, 3500.4399296112756, 3562.09166662502, 3576.7675101118343, 3579.2125732339105, 4970.453250142481, 4996.725180115092, 5019.749012549358, 5040.6471546991725, 5042.193249080129, 4603.204750147445, 4609.101225261075, 4614.423197100363, 4624.30635404692, 4645.65157613177, 5256.715776642024, 5344.812931547377, 5351.946480535184, 5391.218273350381, 5414.296141501619, 3902.2390633826417, 3913.7847418391975, 3917.902052448683, 3919.157381163035, 3919.7648141111627, 5522.751476183581, 5526.917096243559, 5533.22534845122, 5539.9169012464445, 5562.443755624446, 3844.442555829389, 3962.0, 3971.947433930011, 3979.0450291930306, 3987.92024159521, 3989.162275922031, 4018.517777866675, 4026.8187931542566, 4031.657309128757, 4050.8987275318354, 4559.0, 4568.428946381702, 4569.223232050559, 4588.357629931812, 4605.470370907322, 5255.5795536357, 5354.598405119632, 5374.328208973879, 5407.405185429599, 5409.350877894599, 1339.9665008374868, 1347.7035052288481, 1352.1751731443812, 1356.2472827580787, 1356.85752995936, 888.684516996457, 908.8636704494379, 918.3938600523622, 918.8254231695956, 924.4453328003144, 5019.924701129486, 5025.673331233458, 5046.4953504649975, 5058.35603428887, 5061.164907790247, 5277.123967437756, 5297.470252974672, 5297.54970827484, 5307.124324486416, 5341.172118321666, 4810.735409552508, 5021.347224860745, 5061.544460998454, 5070.7464626768515, 5073.594112471067, 2817.7464028237555, 2821.562657788046, 2824.4633519631616, 2828.674702409249, 2833.761328550422, 5476.58925580583, 5479.671219726829, 5482.205080263419, 5486.725663716878, 5489.423610520837, 5186.325777648882, 5328.227407025959, 5363.632273770164, 5367.543758780547, 5400.91898621521, 5392.352917649907, 5421.783128674967, 5485.6982865942755, 5504.339479262514, 5524.0, 5251.921221181762, 5269.420363759907, 5279.52484276417, 5310.601704872072, 5321.928724525664, 5174.922376164285, 5505.889882202414, 5540.36285827135, 5577.609567330248, 5592.636478628876, 5779.0464573344925, 5782.971085144715, 5797.594168408171, 5830.416958304305, 5832.9708351457975, 3809.561900381381, 3885.9417108742828, 3917.6474117329576, 3923.215356928683, 3935.763854168759, 5448.945510545002, 5480.588955828249, 5487.0, 5507.697076660822, 5536.833894983157, 5387.114509921496, 5407.756650950638, 5414.10667239901, 5421.9186712200135, 5438.592105592017, 5194.0915834536, 5383.838484845461, 5416.862458883678, 5440.945590544044, 5444.8094316698725, 5340.172273297645, 5349.57203423733, 5356.3572371315695, 5361.0, 5362.919556206738, 5240.292560504283, 5434.972825135849, 5483.561315094863, 5484.698341591251, 5495.972520137374, 5292.9735351323, 5300.920486192787, 5305.708186049805, 5326.920096198638, 5327.9733601331745], "value": 4092.0184601375618, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.2294010998066, 723.6020188896106, 728.6903066196865, 756.2777547442194, 779.1351599724305, 13235.272060036707, 14216.857831421683, 14227.857721422783, 14249.0, 14268.571942841705, 13080.607581772538, 13189.736205275913, 13256.46974121034, 13297.202167869935, 13328.666783330422, 656.9014647802844, 677.8136012596551, 765.47947395771, 775.3293401207943, 777.9883301750478, 757.4962649837851, 778.0818634011864, 787.5825812319451, 789.1201310538737, 789.4394979564487, 842.2377748137945, 844.8690452979798, 850.306999795168, 852.2542775071812, 852.735651947895, 691.2948792231896, 712.3695529456469, 838.3837879158814, 842.2209456252972, 842.6839935024396, 588.2529187931303, 606.654207101952, 608.4371955940737, 608.856918624123, 609.5732986909137, 12280.078994075391, 12332.075094367869, 12342.074344424203, 12347.814782778265, 12358.134930554865, 12932.28872412017, 13084.672883177902, 13254.867451325556, 13319.933400332937, 13341.799873001914, 11592.826107608393, 11951.402429878472, 12003.639890803202, 12062.638120856389, 12099.274043557414, 4137.958620413818, 4175.248455278049, 4219.0, 4256.553061928484, 4287.8070486828165, 57349.713251433895, 57901.71049144727, 57951.420485795024, 58671.706641466946, 60757.392426076054, 10398.792024159475, 10399.48002599867, 10516.01007867137, 10635.351520514332, 10635.52140153695, 16926.322947082226, 17071.829281707272, 17204.91397543004, 18266.908665456587, 19873.403797886087, 7516.624168791539, 7636.198199189116, 7637.618119094023, 7668.846623067508, 7689.231076892266, 4295.978520107379, 4345.543717909574, 4345.630621397154, 4440.600345968878, 4540.977295113569, 3086.4135814195233, 3161.0990867602623, 3163.8892638757534, 3165.1295893629435, 3167.3506931079073, 560.2408736162523, 562.9690367029852, 566.5156291370901, 580.6632153351046, 582.659144400522, 574.7068994812676, 653.6731634182909, 674.1842370731372, 675.3853992866468, 690.3648643248224, 8549.145085491402, 8688.220624028569, 8703.347248956354, 8831.381803273838, 8895.154960278764, 550.3918170421626, 574.6638216643227, 677.6814896998403, 726.0416250549224, 726.1794172584945, 4187.392828039917, 4360.978195109004, 4593.517680643551, 4658.0036844086635, 4683.836065737751, 46414.53585464102, 51783.48216517862, 72568.63715681387, 79230.0, 79358.6032069836, 67825.0, 68391.65804170947, 70051.04566111938, 70795.0, 71494.64252678807, 58552.0, 60252.39747602556, 60434.697826510586, 61795.382046178966, 63772.68113659309, 7983.1617680142745, 8097.514149150834, 8208.630611622371, 8271.379646526415, 8382.958085209535, 36344.09139771527, 38023.61976380201, 38993.22013559656, 40851.59148408597, 43766.0, 39107.0, 39588.20823583454, 48877.0, 55351.44648553463, 56838.7158064207, 11393.316401015965, 11853.0, 11899.46452409661, 12034.277943323428, 12278.201916875374, 12979.805302920653, 13634.659133521742, 13660.86339136596, 13674.521391751241, 14019.649508762363, 12363.93818030904, 12548.24423440821, 12786.552470663482, 13040.54358097462, 13238.286181035199, 11946.34295113777, 12026.518939242333, 12180.269183848643, 12239.75520489603, 12774.936125319315, 12082.0, 12164.756704865677, 12174.695632609255, 12203.511859525875, 12288.692782680504, 10056.597736090474, 10189.643362482277, 10288.0, 10335.586576536854, 10584.153267738408, 10094.596216151273, 10107.848382274418, 10262.794744105226, 10333.638322658671, 10503.212259080447], "value": 6645.881892993912, "unit": "score"}], "name": "dromaeo_css", "value": 6814.95726275618, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 115.0, 113.0, 113.0, 114.0, 113.0, 112.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 120.0, 122.0, 162.0, 123.0, 126.0, 122.0, 121.0, 155.0, 123.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 159.0, 160.0, 161.0, 160.0, 161.0, 162.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 106.0, 76.0, 76.0, 76.0, 75.0, 101.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 112.0, 84.0, 83.0, 83.0, 107.0, 83.0, 83.0, 122.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 148.0, 147.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 53.0, 52.0, 53.0, 54.0, 53.0, 53.0, 54.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 64.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 88.0, 80.0, 82.0, 87.0, 81.0, 80.0, 79.0, 87.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 143.0, 142.0, 130.0, 137.0, 134.0, 140.0, 139.0, 177.0, 142.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 165.0, 166.0, 165.0, 166.0, 168.0, 171.0, 170.0, 166.0, 165.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 70.0, 65.0, 64.0, 64.0, 65.0, 63.0, 64.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1500.3, "alertThreshold": 2.0}]} 22:43:23 INFO - Return code: 0 22:43:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:43:24 INFO - # TBPL SUCCESS # 22:43:24 INFO - Running post-action listener: _resource_record_post_action 22:43:24 INFO - [mozharness: 2016-11-15 06:43:24.021941Z] Finished run-tests step (success) 22:43:24 INFO - Running post-run listener: _resource_record_post_run 22:43:24 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395554816; Read time: 8; Write time: 312504 22:43:24 INFO - TinderboxPrint: CPU usage
13.1% 22:43:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 22:43:24 INFO - TinderboxPrint: I/O write bytes / time
395,554,816 / 312,504 22:43:24 INFO - TinderboxPrint: CPU idle
8,293.9 (86.9%) 22:43:24 INFO - TinderboxPrint: CPU user
1,200.6 (12.6%) 22:43:24 INFO - TinderboxPrint: Swap in / out
0 / 0 22:43:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106602496; Read time: 0; Write time: 100672 22:43:24 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 288608256; Read time: 0; Write time: 211784 22:43:24 INFO - Running post-run listener: _upload_blobber_files 22:43:24 INFO - Blob upload gear active. 22:43:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:43:24 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 22:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:43:25 INFO - (blobuploader) - INFO - Open directory for files ... 22:43:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:43:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:43:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:25 INFO - (blobuploader) - INFO - Done attempting. 22:43:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:43:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:43:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:30 INFO - (blobuploader) - INFO - Done attempting. 22:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:31 INFO - (blobuploader) - INFO - Done attempting. 22:43:31 INFO - (blobuploader) - INFO - Iteration through files over. 22:43:31 INFO - Return code: 0 22:43:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:43:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:43:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e86637b184740eaa50ada6548b32ff8a3274264d4f223dafcbee02694d05249240c1d65b28f2fdb76f27412440948485dcbde1a67c46485dd1614723b1d39db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6d6e2b9d1a0b98b8f3a7979d7dbd824ed3a484f05116737544328eac595483646bea8f3fc48ac179eba6b7792c613af25e4128131fd5d57d093c4c0eab75764", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b9f715b45c6150006093a40fa4c6d7a558b4abd570e337dd28654a391b9e0211d0c5bd37bb47d668e701e2a15475272e9603c6659282b2392ea889d844bf58"} 22:43:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:43:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:43:31 INFO - Contents: 22:43:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e86637b184740eaa50ada6548b32ff8a3274264d4f223dafcbee02694d05249240c1d65b28f2fdb76f27412440948485dcbde1a67c46485dd1614723b1d39db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6d6e2b9d1a0b98b8f3a7979d7dbd824ed3a484f05116737544328eac595483646bea8f3fc48ac179eba6b7792c613af25e4128131fd5d57d093c4c0eab75764", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b9f715b45c6150006093a40fa4c6d7a558b4abd570e337dd28654a391b9e0211d0c5bd37bb47d668e701e2a15475272e9603c6659282b2392ea889d844bf58"} 22:43:31 INFO - Running post-run listener: copy_logs_to_upload_dir 22:43:31 INFO - Copying logs to upload dir... 22:43:31 INFO - mkdir: /builds/slave/test/build/upload/logs 22:43:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.220177 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-11-14 22:43:30.880382) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:43:30.883513) ========= 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=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e86637b184740eaa50ada6548b32ff8a3274264d4f223dafcbee02694d05249240c1d65b28f2fdb76f27412440948485dcbde1a67c46485dd1614723b1d39db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6d6e2b9d1a0b98b8f3a7979d7dbd824ed3a484f05116737544328eac595483646bea8f3fc48ac179eba6b7792c613af25e4128131fd5d57d093c4c0eab75764", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b9f715b45c6150006093a40fa4c6d7a558b4abd570e337dd28654a391b9e0211d0c5bd37bb47d668e701e2a15475272e9603c6659282b2392ea889d844bf58"} build_url:https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011760 build_url: 'https://queue.taskcluster.net/v1/task/ItEEUsu3S4Cq0UpLHmqIjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e86637b184740eaa50ada6548b32ff8a3274264d4f223dafcbee02694d05249240c1d65b28f2fdb76f27412440948485dcbde1a67c46485dd1614723b1d39db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6d6e2b9d1a0b98b8f3a7979d7dbd824ed3a484f05116737544328eac595483646bea8f3fc48ac179eba6b7792c613af25e4128131fd5d57d093c4c0eab75764", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57b9f715b45c6150006093a40fa4c6d7a558b4abd570e337dd28654a391b9e0211d0c5bd37bb47d668e701e2a15475272e9603c6659282b2392ea889d844bf58"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 22:43:30.909198) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:43:30.909553) ========= 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=923d835db2ba3686ebe126b700000192-1479190962.113172-982574626 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 22:43:30.994469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:43:30.994898) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 22:43:30.995327) ========= ========= Total master_lag: 0.18 =========