builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1477546068.99 results: success (0) buildid: 20161026193001 builduid: 496b3b7034114f5d950a3311824d3c2a revision: 3f4c3a3cabaf94958834d3a8935adfb4a887942d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:48.988424) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:48.988881) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:48.989160) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.004072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.004455) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-26 22:27: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.1M=0s 2016-10-26 22:27:49 (51.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092163 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.110599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.110936) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013802 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.160501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.160901) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3f4c3a3cabaf94958834d3a8935adfb4a887942d --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 3f4c3a3cabaf94958834d3a8935adfb4a887942d --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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-26 22:27:49,577 truncating revision to first 12 chars 2016-10-26 22:27:49,577 Setting DEBUG logging. 2016-10-26 22:27:49,577 attempt 1/10 2016-10-26 22:27:49,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3f4c3a3cabaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 22:27:49,897 unpacking tar archive at: mozilla-central-3f4c3a3cabaf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.645871 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.820482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.820805) ========= script_repo_revision: 3f4c3a3cabaf94958834d3a8935adfb4a887942d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.821189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.821463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 22:27:49.830051) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-26 22:27:49.830392) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:27:50 INFO - MultiFileLogger online at 20161026 22:27:50 in /builds/slave/test 22:27: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:27:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:27:50 INFO - {'append_to_log': False, 22:27:50 INFO - 'base_work_dir': '/builds/slave/test', 22:27:50 INFO - 'blob_upload_branch': 'Firefox', 22:27:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:27:50 INFO - 'branch': 'Firefox', 22:27:50 INFO - 'buildbot_json_path': 'buildprops.json', 22:27:50 INFO - 'config_files': ('talos/linux_config.py',), 22:27:50 INFO - 'default_actions': ('clobber', 22:27:50 INFO - 'read-buildbot-config', 22:27:50 INFO - 'download-and-extract', 22:27:50 INFO - 'populate-webroot', 22:27:50 INFO - 'create-virtualenv', 22:27:50 INFO - 'install', 22:27:50 INFO - 'run-tests'), 22:27:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:27:50 INFO - 'download_minidump_stackwalk': True, 22:27:50 INFO - 'download_symbols': 'ondemand', 22:27:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:27:50 INFO - 'tooltool.py': '/tools/tooltool.py', 22:27:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:27:50 INFO - '/tools/misc-python/virtualenv.py')}, 22:27:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:27:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:27:50 INFO - 'installer_path': 'installer.exe', 22:27:50 INFO - 'log_level': 'info', 22:27:50 INFO - 'log_name': 'talos', 22:27:50 INFO - 'log_to_console': True, 22:27:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:27:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:27:50 INFO - 'opt_config_files': (), 22:27:50 INFO - 'pip_index': False, 22:27:50 INFO - 'sps_profile': False, 22:27:50 INFO - 'sps_profile_interval': 0, 22:27:50 INFO - 'suite': 'dromaeojs', 22:27:50 INFO - 'system_bits': '32', 22:27:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:27:50 INFO - 'title': 'talos-linux64-ix-006', 22:27:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:27:50 INFO - 'use_talos_json': True, 22:27:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:27:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:27:50 INFO - 'work_dir': 'build'} 22:27:50 INFO - [mozharness: 2016-10-27 05:27:50.339558Z] Running clobber step. 22:27:50 INFO - Running pre-action listener: _resource_record_pre_action 22:27:50 INFO - Running main action method: clobber 22:27:50 INFO - rmtree: /builds/slave/test/build 22:27:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:27:50 INFO - Running post-action listener: _resource_record_post_action 22:27:50 INFO - [mozharness: 2016-10-27 05:27:50.799553Z] Finished clobber step (success) 22:27:50 INFO - [mozharness: 2016-10-27 05:27:50.799655Z] Running read-buildbot-config step. 22:27:50 INFO - Running pre-action listener: _resource_record_pre_action 22:27:50 INFO - Running main action method: read_buildbot_config 22:27:50 INFO - Using buildbot properties: 22:27:50 INFO - { 22:27:50 INFO - "project": "", 22:27:50 INFO - "product": "firefox", 22:27:50 INFO - "script_repo_revision": "production", 22:27:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:27:50 INFO - "repository": "", 22:27:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:27:50 INFO - "buildid": "20161026193001", 22:27:50 INFO - "pgo_build": "True", 22:27:50 INFO - "basedir": "/builds/slave/test", 22:27:50 INFO - "buildnumber": 38, 22:27:50 INFO - "slavename": "talos-linux64-ix-006", 22:27:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:27:50 INFO - "platform": "ubuntu64_hw", 22:27:50 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:27:50 INFO - "branch": "mozilla-central", 22:27:50 INFO - "repo_path": "mozilla-central", 22:27:50 INFO - "revision": "3f4c3a3cabaf94958834d3a8935adfb4a887942d", 22:27:50 INFO - "stage_platform": "linux64-pgo", 22:27:50 INFO - "builduid": "496b3b7034114f5d950a3311824d3c2a", 22:27:50 INFO - "slavebuilddir": "test" 22:27:50 INFO - } 22:27:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 22:27:50 INFO - Running post-action listener: _resource_record_post_action 22:27:50 INFO - [mozharness: 2016-10-27 05:27:50.802103Z] Finished read-buildbot-config step (success) 22:27:50 INFO - [mozharness: 2016-10-27 05:27:50.802192Z] Running download-and-extract step. 22:27:50 INFO - Running pre-action listener: _resource_record_pre_action 22:27:50 INFO - Running main action method: download_and_extract 22:27:50 INFO - mkdir: /builds/slave/test/build/tests 22:27:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:27:50 INFO - trying https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:27:50 INFO - Downloading https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:27:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:27:51 INFO - Downloaded 1361 bytes. 22:27:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:27:51 INFO - Using the following test package requirements: 22:27:51 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:27:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:27:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:27:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'jsshell-linux-x86_64.zip'], 22:27:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:27:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:27:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:27:51 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:27:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:27:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:27:51 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:27:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:27:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:27:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:27:51 INFO - Fetch https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:27:51 INFO - Http code: 200 22:27:51 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 22:27:51 INFO - x-amz-cf-id: D-OKc7Yfk_AQbqhGGlZBOULk27w4PJhBqAsiLRa-JGp9FqbmhUHSWQ== 22:27:51 INFO - x-amz-version-id: 0rhzQB3EmZpQbQrOwGV6FOFYF77SudoA 22:27:52 INFO - Expected file size: 21992532 22:27:52 INFO - Obtained file size: 21992532 22:27:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:27:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:27:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:27:56 INFO - Fetch https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:27:56 INFO - Http code: 200 22:27:56 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 22:27:56 INFO - x-amz-cf-id: 7AHcjZFK3zJTV4Rvr4iYzDPvc0TGo7NivYz6m1NuGcGTQOqvbRnlOA== 22:27:56 INFO - x-amz-version-id: 0rhzQB3EmZpQbQrOwGV6FOFYF77SudoA 22:27:57 INFO - Expected file size: 21992532 22:27:57 INFO - Obtained file size: 21992532 22:28:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 22:28:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:28:01 INFO - Fetch https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:28:02 INFO - Http code: 200 22:28:02 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:28:02 INFO - x-amz-cf-id: udq2nHzIjddmH8Abz6EvLMz7z0AM6kVGCVpNekRq7FmRTRUwMzY0sw== 22:28:02 INFO - x-amz-version-id: m4Nj9yIdAapSVW2Ez0JPqPO1jjUcWtqT 22:28:02 INFO - Expected file size: 14409028 22:28:02 INFO - Obtained file size: 14409028 22:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:28:02 INFO - trying https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:28:02 INFO - Downloading https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:28:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:28:03 INFO - Downloaded 59339069 bytes. 22:28:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:28:03 INFO - mkdir: /builds/slave/test/properties 22:28:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:28:03 INFO - Writing to file /builds/slave/test/properties/build_url 22:28:03 INFO - Contents: 22:28:03 INFO - build_url:https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:28:04 INFO - Running post-action listener: _resource_record_post_action 22:28:04 INFO - Running post-action listener: set_extra_try_arguments 22:28:04 INFO - [mozharness: 2016-10-27 05:28:04.529178Z] Finished download-and-extract step (success) 22:28:04 INFO - [mozharness: 2016-10-27 05:28:04.529404Z] Running populate-webroot step. 22:28:04 INFO - Running pre-action listener: _resource_record_pre_action 22:28:04 INFO - Running main action method: populate_webroot 22:28:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:28:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:28:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:28:04 INFO - '--tppagecycles', 22:28:04 INFO - '7'], 22:28:04 INFO - 'tests': ['tsvgm']}}, 22:28:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:28:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:28:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:28:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:28:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:28:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:28:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['damp', 'tps']}, 22:28:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'tests': ['damp', 'tps']}, 22:28:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['dromaeo_dom']}, 22:28:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:28:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['basic_compositor_video']}, 22:28:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:28:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'other-e10s': {'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['a11yr', 22:28:04 INFO - 'ts_paint', 22:28:04 INFO - 'tpaint', 22:28:04 INFO - 'sessionrestore', 22:28:04 INFO - 'sessionrestore_no_auto_restore', 22:28:04 INFO - 'tabpaint']}, 22:28:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['tsvgx', 22:28:04 INFO - 'tsvgr_opacity', 22:28:04 INFO - 'tart', 22:28:04 INFO - 'tscrollx', 22:28:04 INFO - 'cart']}, 22:28:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:28:04 INFO - 'tsvgr_opacity', 22:28:04 INFO - 'tart', 22:28:04 INFO - 'tscrollx', 22:28:04 INFO - 'cart']}, 22:28:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'talos_options': ['--disable-e10s'], 22:28:04 INFO - 'tests': ['tp5o']}, 22:28:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'tests': ['tp5o']}, 22:28:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'talos_options': ['--disable-e10s', 22:28:04 INFO - '--xperf_path', 22:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:28:04 INFO - 'tests': ['tp5n']}, 22:28:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:04 INFO - 'talos_options': ['--xperf_path', 22:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:28:04 INFO - 'tests': ['tp5n']}}, 22:28:04 INFO - 'talos.zip': {'path': '', 22:28:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:28:04 INFO - Running post-action listener: _resource_record_post_action 22:28:04 INFO - [mozharness: 2016-10-27 05:28:04.551446Z] Finished populate-webroot step (success) 22:28:04 INFO - [mozharness: 2016-10-27 05:28:04.551534Z] Running create-virtualenv step. 22:28:04 INFO - Running pre-action listener: _resource_record_pre_action 22:28:04 INFO - Running main action method: create_virtualenv 22:28:04 INFO - Creating virtualenv /builds/slave/test/build/venv 22:28:04 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:28:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:28:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:28:04 INFO - Using real prefix '/usr' 22:28:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:28:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:28:07 INFO - Installing pip.................done. 22:28:07 INFO - Return code: 0 22:28:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:28:07 INFO - Reading from file tmpfile_stdout 22:28:07 INFO - Output received: 22:28: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:28:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:28: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:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:07 INFO - Using env: {'DISPLAY': ':0', 22:28:07 INFO - 'HOME': '/home/cltbld', 22:28:07 INFO - 'LANG': 'en_US.UTF-8', 22:28:07 INFO - 'LANGUAGE': 'en_US:en', 22:28:07 INFO - 'LOGNAME': 'cltbld', 22:28:07 INFO - 'MAIL': '/var/mail/cltbld', 22:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:07 INFO - 'MOZ_NO_REMOTE': '1', 22:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:07 INFO - 'NO_EM_RESTART': '1', 22:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:07 INFO - 'PWD': '/builds/slave/test', 22:28:07 INFO - 'SHELL': '/bin/bash', 22:28:07 INFO - 'SHLVL': '1', 22:28:07 INFO - 'TERM': 'linux', 22:28:07 INFO - 'TMOUT': '86400', 22:28:07 INFO - 'USER': 'cltbld', 22:28:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:07 INFO - '_': '/tools/buildbot/bin/python'} 22:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:07 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:28:07 INFO - Cleaning up... 22:28:07 INFO - Return code: 0 22:28:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:28: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:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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', '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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:28: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 psutil>=3.1.1 22:28:07 INFO - Using env: {'DISPLAY': ':0', 22:28:07 INFO - 'HOME': '/home/cltbld', 22:28:07 INFO - 'LANG': 'en_US.UTF-8', 22:28:07 INFO - 'LANGUAGE': 'en_US:en', 22:28:07 INFO - 'LOGNAME': 'cltbld', 22:28:07 INFO - 'MAIL': '/var/mail/cltbld', 22:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:07 INFO - 'MOZ_NO_REMOTE': '1', 22:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:07 INFO - 'NO_EM_RESTART': '1', 22:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:07 INFO - 'PWD': '/builds/slave/test', 22:28:07 INFO - 'SHELL': '/bin/bash', 22:28:07 INFO - 'SHLVL': '1', 22:28:07 INFO - 'TERM': 'linux', 22:28:07 INFO - 'TMOUT': '86400', 22:28:07 INFO - 'USER': 'cltbld', 22:28:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:07 INFO - '_': '/tools/buildbot/bin/python'} 22:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:07 INFO - Downloading/unpacking psutil>=3.1.1 22:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:28:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:28:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:28:09 INFO - Installing collected packages: psutil 22:28:09 INFO - Running setup.py install for psutil 22:28:09 INFO - building 'psutil._psutil_linux' extension 22:28:09 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:28: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:28:10 INFO - building 'psutil._psutil_posix' extension 22:28: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:28: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:28:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:28:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:28:10 INFO - Successfully installed psutil 22:28:10 INFO - Cleaning up... 22:28:10 INFO - Return code: 0 22:28:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:28: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:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:10 INFO - Using env: {'DISPLAY': ':0', 22:28:10 INFO - 'HOME': '/home/cltbld', 22:28:10 INFO - 'LANG': 'en_US.UTF-8', 22:28:10 INFO - 'LANGUAGE': 'en_US:en', 22:28:10 INFO - 'LOGNAME': 'cltbld', 22:28:10 INFO - 'MAIL': '/var/mail/cltbld', 22:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:10 INFO - 'MOZ_NO_REMOTE': '1', 22:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:10 INFO - 'NO_EM_RESTART': '1', 22:28:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:10 INFO - 'PWD': '/builds/slave/test', 22:28:10 INFO - 'SHELL': '/bin/bash', 22:28:10 INFO - 'SHLVL': '1', 22:28:10 INFO - 'TERM': 'linux', 22:28:10 INFO - 'TMOUT': '86400', 22:28:10 INFO - 'USER': 'cltbld', 22:28:10 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:10 INFO - '_': '/tools/buildbot/bin/python'} 22:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:28:10 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:28:10 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:28:10 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:28:10 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:28:10 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:28:10 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:28:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:28:12 INFO - Installing collected packages: mozsystemmonitor 22:28:12 INFO - Running setup.py install for mozsystemmonitor 22:28:12 INFO - Successfully installed mozsystemmonitor 22:28:12 INFO - Cleaning up... 22:28:12 INFO - Return code: 0 22:28:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:28:12 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:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:12 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:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:12 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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28:12 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:28:12 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:28:12 INFO - Using env: {'DISPLAY': ':0', 22:28:12 INFO - 'HOME': '/home/cltbld', 22:28:12 INFO - 'LANG': 'en_US.UTF-8', 22:28:12 INFO - 'LANGUAGE': 'en_US:en', 22:28:12 INFO - 'LOGNAME': 'cltbld', 22:28:12 INFO - 'MAIL': '/var/mail/cltbld', 22:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:12 INFO - 'MOZ_NO_REMOTE': '1', 22:28:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:12 INFO - 'NO_EM_RESTART': '1', 22:28:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:12 INFO - 'PWD': '/builds/slave/test', 22:28:12 INFO - 'SHELL': '/bin/bash', 22:28:12 INFO - 'SHLVL': '1', 22:28:12 INFO - 'TERM': 'linux', 22:28:12 INFO - 'TMOUT': '86400', 22:28:12 INFO - 'USER': 'cltbld', 22:28:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:12 INFO - '_': '/tools/buildbot/bin/python'} 22:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:12 INFO - Downloading/unpacking jsonschema==2.5.1 22:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:28:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:28:14 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:28:14 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:28:14 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:28:14 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:28:14 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:28:14 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:28:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:28:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:28:15 INFO - warning: no files found matching '*.txt' 22:28:15 INFO - no previously-included directories found matching 'build' 22:28:15 INFO - no previously-included directories found matching 'dist' 22:28:15 INFO - no previously-included directories found matching '.git*' 22:28:15 INFO - Installing collected packages: jsonschema, functools32 22:28:15 INFO - Running setup.py install for functools32 22:28:15 INFO - warning: no files found matching '*.txt' 22:28:15 INFO - no previously-included directories found matching 'build' 22:28:15 INFO - no previously-included directories found matching 'dist' 22:28:15 INFO - no previously-included directories found matching '.git*' 22:28:15 INFO - Successfully installed jsonschema functools32 22:28:15 INFO - Cleaning up... 22:28:15 INFO - Return code: 0 22:28:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:28: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:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:15 INFO - Using env: {'DISPLAY': ':0', 22:28:15 INFO - 'HOME': '/home/cltbld', 22:28:15 INFO - 'LANG': 'en_US.UTF-8', 22:28:15 INFO - 'LANGUAGE': 'en_US:en', 22:28:15 INFO - 'LOGNAME': 'cltbld', 22:28:15 INFO - 'MAIL': '/var/mail/cltbld', 22:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:15 INFO - 'MOZ_NO_REMOTE': '1', 22:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:15 INFO - 'NO_EM_RESTART': '1', 22:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:15 INFO - 'PWD': '/builds/slave/test', 22:28:15 INFO - 'SHELL': '/bin/bash', 22:28:15 INFO - 'SHLVL': '1', 22:28:15 INFO - 'TERM': 'linux', 22:28:15 INFO - 'TMOUT': '86400', 22:28:15 INFO - 'USER': 'cltbld', 22:28:15 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:15 INFO - '_': '/tools/buildbot/bin/python'} 22:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:28:15 INFO - Cleaning up... 22:28:15 INFO - Return code: 0 22:28:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:28: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:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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', '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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:28: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 blobuploader==1.2.4 22:28:15 INFO - Using env: {'DISPLAY': ':0', 22:28:15 INFO - 'HOME': '/home/cltbld', 22:28:15 INFO - 'LANG': 'en_US.UTF-8', 22:28:15 INFO - 'LANGUAGE': 'en_US:en', 22:28:15 INFO - 'LOGNAME': 'cltbld', 22:28:15 INFO - 'MAIL': '/var/mail/cltbld', 22:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:15 INFO - 'MOZ_NO_REMOTE': '1', 22:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:15 INFO - 'NO_EM_RESTART': '1', 22:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:15 INFO - 'PWD': '/builds/slave/test', 22:28:15 INFO - 'SHELL': '/bin/bash', 22:28:15 INFO - 'SHLVL': '1', 22:28:15 INFO - 'TERM': 'linux', 22:28:15 INFO - 'TMOUT': '86400', 22:28:15 INFO - 'USER': 'cltbld', 22:28:15 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:15 INFO - '_': '/tools/buildbot/bin/python'} 22:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:15 INFO - Downloading/unpacking blobuploader==1.2.4 22:28: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:28: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:28: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:28: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:28: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:28: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:28:17 INFO - Downloading blobuploader-1.2.4.tar.gz 22:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:28:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:17 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:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:28:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:28: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:28: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:28: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:28: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:28: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:28: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:28:18 INFO - Downloading docopt-0.6.1.tar.gz 22:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:28:18 INFO - Installing collected packages: blobuploader, requests, docopt 22:28:18 INFO - Running setup.py install for blobuploader 22:28:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:28:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:28:18 INFO - Running setup.py install for requests 22:28:18 INFO - Running setup.py install for docopt 22:28:19 INFO - Successfully installed blobuploader requests docopt 22:28:19 INFO - Cleaning up... 22:28:19 INFO - Return code: 0 22:28:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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:28:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:19 INFO - Using env: {'DISPLAY': ':0', 22:28:19 INFO - 'HOME': '/home/cltbld', 22:28:19 INFO - 'LANG': 'en_US.UTF-8', 22:28:19 INFO - 'LANGUAGE': 'en_US:en', 22:28:19 INFO - 'LOGNAME': 'cltbld', 22:28:19 INFO - 'MAIL': '/var/mail/cltbld', 22:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:19 INFO - 'MOZ_NO_REMOTE': '1', 22:28:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:19 INFO - 'NO_EM_RESTART': '1', 22:28:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:19 INFO - 'PWD': '/builds/slave/test', 22:28:19 INFO - 'SHELL': '/bin/bash', 22:28:19 INFO - 'SHLVL': '1', 22:28:19 INFO - 'TERM': 'linux', 22:28:19 INFO - 'TMOUT': '86400', 22:28:19 INFO - 'USER': 'cltbld', 22:28:19 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:19 INFO - '_': '/tools/buildbot/bin/python'} 22:28:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:19 INFO - Running setup.py (path:/tmp/pip-cepe1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:19 INFO - Running setup.py (path:/tmp/pip-DLCIoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:19 INFO - Running setup.py (path:/tmp/pip-zNPtRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:19 INFO - Running setup.py (path:/tmp/pip-IMADiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:19 INFO - Running setup.py (path:/tmp/pip-m2J8VR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:19 INFO - Running setup.py (path:/tmp/pip-meP_yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:19 INFO - Running setup.py (path:/tmp/pip-VIxN7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:19 INFO - Running setup.py (path:/tmp/pip-rfVJhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:19 INFO - Running setup.py (path:/tmp/pip-hKiyS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:20 INFO - Running setup.py (path:/tmp/pip-YJZTyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:20 INFO - Running setup.py (path:/tmp/pip-sU5_W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:20 INFO - Running setup.py (path:/tmp/pip-uYNTta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:20 INFO - Running setup.py (path:/tmp/pip-RBuQW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:20 INFO - Running setup.py (path:/tmp/pip-54ZWYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:20 INFO - Running setup.py (path:/tmp/pip-fsTndh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:20 INFO - Running setup.py (path:/tmp/pip-WlCN71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:20 INFO - Running setup.py (path:/tmp/pip-VsblE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:28:20 INFO - Running setup.py install for manifestparser 22:28:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:28:20 INFO - Running setup.py install for mozcrash 22:28:20 INFO - Running setup.py install for mozdebug 22:28:20 INFO - Running setup.py install for mozdevice 22:28:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozfile 22:28:21 INFO - Running setup.py install for mozhttpd 22:28:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozinfo 22:28:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozInstall 22:28:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozleak 22:28:21 INFO - Running setup.py install for mozlog 22:28:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for moznetwork 22:28:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozprocess 22:28:21 INFO - Running setup.py install for mozprofile 22:28:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:28:21 INFO - Running setup.py install for mozrunner 22:28:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:28:22 INFO - Running setup.py install for mozscreenshot 22:28:22 INFO - Running setup.py install for moztest 22:28:22 INFO - Running setup.py install for mozversion 22:28:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:28:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:28:22 INFO - Cleaning up... 22:28:22 INFO - Return code: 0 22:28:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:22 INFO - Using env: {'DISPLAY': ':0', 22:28:22 INFO - 'HOME': '/home/cltbld', 22:28:22 INFO - 'LANG': 'en_US.UTF-8', 22:28:22 INFO - 'LANGUAGE': 'en_US:en', 22:28:22 INFO - 'LOGNAME': 'cltbld', 22:28:22 INFO - 'MAIL': '/var/mail/cltbld', 22:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:22 INFO - 'MOZ_NO_REMOTE': '1', 22:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:22 INFO - 'NO_EM_RESTART': '1', 22:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:22 INFO - 'PWD': '/builds/slave/test', 22:28:22 INFO - 'SHELL': '/bin/bash', 22:28:22 INFO - 'SHLVL': '1', 22:28:22 INFO - 'TERM': 'linux', 22:28:22 INFO - 'TMOUT': '86400', 22:28:22 INFO - 'USER': 'cltbld', 22:28:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:22 INFO - '_': '/tools/buildbot/bin/python'} 22:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:22 INFO - Running setup.py (path:/tmp/pip-cx8pg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28:22 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:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:22 INFO - Running setup.py (path:/tmp/pip-92_wy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:22 INFO - Running setup.py (path:/tmp/pip-8a7hiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28:22 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:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:22 INFO - Running setup.py (path:/tmp/pip-OzhIN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:22 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:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:22 INFO - Running setup.py (path:/tmp/pip-5sCr__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:23 INFO - Running setup.py (path:/tmp/pip-yZVHk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:23 INFO - Running setup.py (path:/tmp/pip-ds0QuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:23 INFO - Running setup.py (path:/tmp/pip-ujU4NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:23 INFO - Running setup.py (path:/tmp/pip-ZpetPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:23 INFO - Running setup.py (path:/tmp/pip-tgPEnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:23 INFO - Running setup.py (path:/tmp/pip-puojEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:23 INFO - Running setup.py (path:/tmp/pip-92ALDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28: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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:23 INFO - Running setup.py (path:/tmp/pip-k1OV5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:23 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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:23 INFO - Running setup.py (path:/tmp/pip-FIM4Vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:23 INFO - Running setup.py (path:/tmp/pip-PBT0jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:23 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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:23 INFO - Running setup.py (path:/tmp/pip-TZ4cgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28:23 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:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:23 INFO - Running setup.py (path:/tmp/pip-xBZfNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28:23 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:28:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:28:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:28:23 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:28:23 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:28:23 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:28:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:25 INFO - Downloading blessings-1.6.tar.gz 22:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:28:25 INFO - Installing collected packages: blessings 22:28:25 INFO - Running setup.py install for blessings 22:28:25 INFO - Successfully installed blessings 22:28:25 INFO - Cleaning up... 22:28:26 INFO - Return code: 0 22:28:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:26 INFO - Reading from file tmpfile_stdout 22:28:26 INFO - Current package versions: 22:28:26 INFO - argparse == 1.2.1 22:28:26 INFO - blessings == 1.6 22:28:26 INFO - blobuploader == 1.2.4 22:28:26 INFO - docopt == 0.6.1 22:28:26 INFO - functools32 == 3.2.3-2 22:28:26 INFO - jsonschema == 2.5.1 22:28:26 INFO - manifestparser == 1.1 22:28:26 INFO - mozInstall == 1.12 22:28:26 INFO - mozcrash == 0.17 22:28:26 INFO - mozdebug == 0.1 22:28:26 INFO - mozdevice == 0.48 22:28:26 INFO - mozfile == 1.2 22:28:26 INFO - mozhttpd == 0.7 22:28:26 INFO - mozinfo == 0.9 22:28:26 INFO - mozleak == 0.1 22:28:26 INFO - mozlog == 3.4 22:28:26 INFO - moznetwork == 0.27 22:28:26 INFO - mozprocess == 0.23 22:28:26 INFO - mozprofile == 0.28 22:28:26 INFO - mozrunner == 6.12 22:28:26 INFO - mozscreenshot == 0.1 22:28:26 INFO - mozsystemmonitor == 0.3 22:28:26 INFO - moztest == 0.7 22:28:26 INFO - mozversion == 1.4 22:28:26 INFO - psutil == 3.1.1 22:28:26 INFO - requests == 1.2.3 22:28:26 INFO - wsgiref == 0.1.2 22:28:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:26 INFO - Using env: {'DISPLAY': ':0', 22:28:26 INFO - 'HOME': '/home/cltbld', 22:28:26 INFO - 'LANG': 'en_US.UTF-8', 22:28:26 INFO - 'LANGUAGE': 'en_US:en', 22:28:26 INFO - 'LOGNAME': 'cltbld', 22:28:26 INFO - 'MAIL': '/var/mail/cltbld', 22:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:26 INFO - 'MOZ_NO_REMOTE': '1', 22:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:26 INFO - 'NO_EM_RESTART': '1', 22:28:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:26 INFO - 'PWD': '/builds/slave/test', 22:28:26 INFO - 'SHELL': '/bin/bash', 22:28:26 INFO - 'SHLVL': '1', 22:28:26 INFO - 'TERM': 'linux', 22:28:26 INFO - 'TMOUT': '86400', 22:28:26 INFO - 'USER': 'cltbld', 22:28:26 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:26 INFO - '_': '/tools/buildbot/bin/python'} 22:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28: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:28: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:28: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:28: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:28: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:28: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:28: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:28: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:28: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:28: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:28: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:28:26 INFO - Cleaning up... 22:28:26 INFO - Return code: 0 22:28:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:28: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:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x7f22978baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20978a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2164790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', '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:28: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:28: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:28:26 INFO - Using env: {'DISPLAY': ':0', 22:28:26 INFO - 'HOME': '/home/cltbld', 22:28:26 INFO - 'LANG': 'en_US.UTF-8', 22:28:26 INFO - 'LANGUAGE': 'en_US:en', 22:28:26 INFO - 'LOGNAME': 'cltbld', 22:28:26 INFO - 'MAIL': '/var/mail/cltbld', 22:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:26 INFO - 'MOZ_NO_REMOTE': '1', 22:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:26 INFO - 'NO_EM_RESTART': '1', 22:28:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:26 INFO - 'PWD': '/builds/slave/test', 22:28:26 INFO - 'SHELL': '/bin/bash', 22:28:26 INFO - 'SHLVL': '1', 22:28:26 INFO - 'TERM': 'linux', 22:28:26 INFO - 'TMOUT': '86400', 22:28:26 INFO - 'USER': 'cltbld', 22:28:26 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:26 INFO - '_': '/tools/buildbot/bin/python'} 22:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:28:26 INFO - Cleaning up... 22:28:26 INFO - Return code: 0 22:28:26 INFO - Running post-action listener: _resource_record_post_action 22:28:26 INFO - Running post-action listener: _start_resource_monitoring 22:28:26 INFO - Starting resource monitoring. 22:28:26 INFO - [mozharness: 2016-10-27 05:28:26.783024Z] Finished create-virtualenv step (success) 22:28:26 INFO - [mozharness: 2016-10-27 05:28:26.783596Z] Running install step. 22:28:26 INFO - Running pre-action listener: _resource_record_pre_action 22:28:26 INFO - Running main action method: install 22:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:26 INFO - Reading from file tmpfile_stdout 22:28:26 INFO - Detecting whether we're running mozinstall >=1.0... 22:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:28:27 INFO - Reading from file tmpfile_stdout 22:28:27 INFO - Output received: 22:28:27 INFO - Usage: mozinstall [options] installer 22:28:27 INFO - Options: 22:28:27 INFO - -h, --help show this help message and exit 22:28:27 INFO - -d DEST, --destination=DEST 22:28:27 INFO - Directory to install application into. [default: 22:28:27 INFO - "/builds/slave/test"] 22:28:27 INFO - --app=APP Application being installed. [default: firefox] 22:28:27 INFO - mkdir: /builds/slave/test/build/application 22:28:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:28:44 INFO - Reading from file tmpfile_stdout 22:28:44 INFO - Output received: 22:28:44 INFO - /builds/slave/test/build/application/firefox/firefox 22:28:44 INFO - Running post-action listener: _resource_record_post_action 22:28:44 INFO - [mozharness: 2016-10-27 05:28:44.554249Z] Finished install step (success) 22:28:44 INFO - [mozharness: 2016-10-27 05:28:44.554578Z] Running run-tests step. 22:28:44 INFO - Running pre-action listener: _resource_record_pre_action 22:28:44 INFO - Running main action method: run_tests 22:28:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:28:44 INFO - Python 2.7.3 22:28:44 INFO - Return code: 0 22:28:44 INFO - grabbing minidump binary from tooltool 22:28: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:28: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 0x2161930>, '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 0x2162830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2162cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:28: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:28: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:28: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:28:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:28:44 INFO - Return code: 0 22:28:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:28:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:28:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:28:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:28:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:28: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/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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:28: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/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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:28:44 INFO - Using env: {'DISPLAY': ':0', 22:28:44 INFO - 'HOME': '/home/cltbld', 22:28:44 INFO - 'LANG': 'en_US.UTF-8', 22:28:44 INFO - 'LANGUAGE': 'en_US:en', 22:28:44 INFO - 'LOGNAME': 'cltbld', 22:28:44 INFO - 'MAIL': '/var/mail/cltbld', 22:28:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:28:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:44 INFO - 'MOZ_NO_REMOTE': '1', 22:28:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:28:44 INFO - 'NO_EM_RESTART': '1', 22:28:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:44 INFO - 'PWD': '/builds/slave/test', 22:28:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:28:44 INFO - 'SHELL': '/bin/bash', 22:28:44 INFO - 'SHLVL': '1', 22:28:44 INFO - 'TERM': 'linux', 22:28:44 INFO - 'TMOUT': '86400', 22:28:44 INFO - 'USER': 'cltbld', 22:28:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817', 22:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:44 INFO - '_': '/tools/buildbot/bin/python'} 22:28: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/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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:28:44 INFO - mozversion application_buildid: 20161026193001 22:28:44 INFO - mozversion application_changeset: 3f4c3a3cabaf94958834d3a8935adfb4a887942d 22:28:44 INFO - mozversion application_display_name: Nightly 22:28:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:28:44 INFO - mozversion application_name: Firefox 22:28:44 INFO - mozversion application_remotingname: firefox 22:28:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:28:44 INFO - mozversion application_vendor: Mozilla 22:28:44 INFO - mozversion application_version: 52.0a1 22:28:44 INFO - mozversion platform_buildid: 20161026193001 22:28:44 INFO - mozversion platform_changeset: 3f4c3a3cabaf94958834d3a8935adfb4a887942d 22:28:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:28:44 INFO - mozversion platform_version: 52.0a1 22:28:44 INFO - using testdate: 1477546124 22:28:44 INFO - actual date: 1477546124 22:28:44 INFO - starting webserver on 'localhost:35751' 22:28:44 INFO - SUITE-START | Running 2 tests 22:28:44 INFO - TEST-START | dromaeo_css 22:28:44 INFO - operating with platform_type : linux_ 22:28:44 INFO - Initialising browser for dromaeo_css test... 22:28:44 INFO - TEST-INFO | started process 8339 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc_qv6c/profile http://localhost:35751/getInfo.html) 22:28:48 INFO - PROCESS | 8339 | __metrics Screen width/height:1600/1200 22:28:48 INFO - PROCESS | 8339 | colorDepth:24 22:28:48 INFO - PROCESS | 8339 | Browser inner width/height: 1024/697 22:28:48 INFO - PROCESS | 8339 | __metrics 22:28:49 INFO - PROCESS | 8339 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:28:49 INFO - PROCESS | 8339 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:28:49 INFO - PROCESS | 8339 | console.error: PushService: 22:28:49 INFO - PROCESS | 8339 | stateChangeProcessEnqueue: Error transitioning state 22:28:49 INFO - PROCESS | 8339 | UnknownError 22:28:50 INFO - TEST-INFO | 8339: exit 0 22:28:50 INFO - Browser initialized. 22:28:50 INFO - Running cycle 1/1 for dromaeo_css test... 22:28:50 INFO - TEST-INFO | started process 8419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc_qv6c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:02 INFO - PROCESS | 8419 | RSS: Main: 172568576 22:29:02 INFO - PROCESS | 8419 | 22:29:54 INFO - PROCESS | 8419 | 1477546194237 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:32:04 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35751/tests/dromaeo/cssquery-ext.html) 22:32:06 INFO - PROCESS | 8419 | RSS: Main: 275288064 22:32:06 INFO - PROCESS | 8419 | 22:35:08 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-ext.html (next: http://localhost:35751/tests/dromaeo/cssquery-jquery.html) 22:35:09 INFO - PROCESS | 8419 | RSS: Main: 276320256 22:35:09 INFO - PROCESS | 8419 | 22:38:11 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35751/tests/dromaeo/cssquery-mootools.html) 22:38:12 INFO - PROCESS | 8419 | RSS: Main: 254693376 22:38:12 INFO - PROCESS | 8419 | 22:41:14 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35751/tests/dromaeo/cssquery-prototype.html) 22:41:14 INFO - PROCESS | 8419 | RSS: Main: 257613824 22:41:14 INFO - PROCESS | 8419 | 22:44:17 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35751/tests/dromaeo/cssquery-yui.html) 22:44:17 INFO - PROCESS | 8419 | RSS: Main: 217006080 22:44:17 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | Cycle 1(1): loaded http://localhost:35751/tests/dromaeo/cssquery-yui.html (next: http://localhost:35751/tests/dromaeo/cssquery-dojo.html) 22:47:20 INFO - PROCESS | 8419 | RSS: Main: 309108736 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | __start_tp_report 22:47:20 INFO - PROCESS | 8419 | _x_x_mozilla_page_load 22:47:20 INFO - PROCESS | 8419 | _x_x_mozilla_page_load_details 22:47:20 INFO - PROCESS | 8419 | |i|pagename|runs| 22:47:20 INFO - PROCESS | 8419 | |0;dojo.html;3025.364673418582;3084.4447999360136;3112;3130.921726956827;3132.1386618679844;5271.65734227275;5285.233641122039;5349.652272602279;5372.113601255798;5381.973090134554;5333.54664853487;5399.055165346068;5403.459654034595;5405.648632838863;5475.6440831345935;4844.685095468801;4915.754212289389;4923.507649235065;4945.085159245542;4960.727160006199;4937.33345998291;4939.555440010398;5020.39755229373;5025.246213068032;5038.067957427869;4971.900561988776;5002.349694539705;5015.749212539358;5022.121128802463;5076.8730781730565;4827.710337379768;4870.902581948377;4872.171730805736;4898;4917.754112294371;4821.517848215186;4838.830640927586;4847.12751704693;4861.440934292566;4875.804967801284;5306.681599104066;5332.30680011596;5382.461753824626;5400.459954004607;5419.241306217133;5714.057180565202;5730.713464326767;5732.168835518826;5738.483536481736;5741.3110426748635;5574.052411090124;5597.048501754712;5774.364819869811;5775.422457754233;5786.392428794958;5293.39126000511;5294.7617357219015;5296.311479507678;5296.496832800878;5296.735163241861;5708.543316534668;5711.628744131618;5717.942820571824;5720.799772007959;5753.712314384264;5621.381648018714;5653.604247702621;5726.914096288559;5732.856678583069;5735.913961290584;5750.396208398141;5757.769689212385;5758.683272419973;5762.481376676119;5762.732198916232;5595.524380427628;5696.715164241771;5697.373288938214;5702.914456283159;5711.942880571224;5695;5755.194272801811;5756.675964528182;5758.395368486333;5765.336986246635;4367.126574685076;4368.2355587772645;4369.715968462104;4370.169667763115;4376.868693939187;3836.8081595920094;3890.144168282973;3901.5708272090046;3901.726879118491;3954.940675889864;5349.090654588729;5350.8127215547265;5353.116735738559;5378.273933019071;5402.8919421611345;5690.231818704505;5691.715414229272;5748.856278592985;5757.8560535986935;5813.418658134153;5248.790048398021;5334.279872217201;5367.221752845816;5378.273933019071;5394.757235924393;4963.8262660806695;4963.851084467472;4964.304997300381;4965.577925876341;5015.699058056528;5873.471387575139;5926.436988486087;5927.051671732502;5932.7923522677365;5996.13056106862;5606.074997625346;5659.717014149359;5681.517071049007;5708.57185711065;5746.655200687972;5940.079287710412;5940.138679891305;5946.702664866826;5967.254093238345;6008.729607167776;5611.691356975405;5614.6631202128;5638.746256418389;5657.321121465286;5667.319921609268;5510.889782204414;5575.553955683455;5632.5775566831835;5735.627184233012;5739.2252045973255;5539.307586551681;5571.498565129158;5574.526165275997;5607.775688972395;5639.577031722719;5517.172424136412;5542.501174894279;5563.360213575491;5576.637518561281;5580.190872323572;5372.5433338166695;5428.375736790162;5432.994895944263;5497.560195184456;5568.860778480571;5500.5874559409;5605.019121653776;5623.8875222496135;5624.971875140762;5685.374608793112;5469.453054694499;5472.315960504937;5479.6164268500825;5484.122540393518;5569.359523654832 22:47:20 INFO - PROCESS | 8419 | |1;ext.html;583.5098517245516;617.4874853871287;625.6934102289879;642.7525402719951;647.7279542592111;31734.523982140287;42284.365734513965;42784;43044.354334685006;46386.30420543686;43709.78145109278;44034.11931761363;44050.7797461013;44069.7796511017;44127;8732.126787321266;8929.330300227477;8931.821363572724;8933.95533022334;8934.508602026886;8004.759857204293;8203.58982050898;10391.22065845061;10634.78730425391;10641;10840.891591084068;10874.18443616732;10884.945575272151;10891.183161262938;10909.18181136414;10691.732706682318;10741.409222492759;10749.892501074968;10750.569977200903;10827.296225745302;10660.840087398696;10730.141588672888;10735.141188704885;10737.24839261252;10745.086667633183;42414.15171696549;42789.1442171158;42797.35803962943;42828;42844.78577607092;3457.3431048101033;3707.9814600926825;4109.445224894631;4145.191687620915;4179.435776170239;28949.855250723824;31572;31677.524837127232;31969.200769980704;32030.679693203238;4289.227938970984;4354.107407981356;4484.461864576239;4659.860204193879;4670.906581868344;340242.1830471868;344856;345989.9999999975;346155;349834;32568;33582.66417335844;58566.12150817741;58944.23167304988;59028.40971590315;35460.29079418397;35517.46723799093;35558.822205888806;35600.643993560254;35651.28697426037;13984.440622375194;14195.64510887236;14206.21865797391;14208.28958552089;14212.502562410473;10030.64892728751;10269.640562580274;11636.12729045325;11867;11867.821982670266;7974.920250797534;8180.550069746221;8226.958865205756;8231.588420578948;8298.751037468886;3167.0657156138996;3177.348643528071;3186.2512309607223;3215.887443939451;3236.7248783853165;971.0871780526282;998.1914649134225;1001.9899801001897;1034.9689509314583;1067.4662668665667;31892.172939247437;32982.835085824416;33571.16072098168;35608.083110857784;35641.28717425689;1052.289704449509;1096.6107032003683;1106.2366966792888;1114.5263263113145;1143.422571601336;16041.759373609406;16986.57533561646;17005.48983530496;17089;17101.65796684084;455840;459402;460021.59589677857;460063;460151;56462.43537564572;56845.71577142088;56866.43133568612;56945.29164125083;56965.14552281719;1217.6773155113901;1233.4572787973264;1305.288617703354;1339.2634051271762;1346.373936119709;30043.24891877633;32173.678263218004;33087.50368744519;33160.834195828866;33711.66288337085;49091.26363104485;49743.50256497389;49783;49799.253011204135;49861.252081219536;370819;372766;372853;375362;375406;26748.19755407341;27590.310242244104;27760.167194984184;28729.56905646459;28971.27571810637;42998.785006074766;45854.77072614749;45871.08257834891;46131.3080303789;46254.76872615615;39301;39377;39555.80222098871;39560.483508133366;41948.79025604852;39537.80231098826;40061.19877602373;40141.59858401495;40180;41827.7908610455;40141;40646;40898.59101408948;41482.79258603688;41655.37516937188;34103.31793364169;35308.29383412266;35386.46920296249;35728.28543429065;35767.28465430729;27516.58725119085;27712.584311234943;27778.86110569434;27952.860235698692;27986 22:47:20 INFO - PROCESS | 8419 | |2;jquery.html;366.3076784876582;369.58052610287314;383.9232153569285;387.1598630970793;415.424636877924;14238.074525155871;18309.633807323862;19395.224191032343;20196.899015504903;20459.18163273471;18364.35724749634;18932.905335473337;19096.140673669684;19105.52236194096;19141.425757227305;5589.385167631557;5792.594518383715;5848.649081055129;5855.063189889597;5862.618929769573;5626.521745651625;5685.488306052454;5711.269148787827;5740.339860915986;5780.566457515703;5694.715264236792;5739.540836733072;5740.311162660487;5743.4543718346695;5744.195812586241;5567.164925261202;5651.7739290428335;5717.3710891802075;5745.540356771448;5764.07774756037;5092.694438333712;5097.847064588068;5101.2093125565225;5101.642884998052;5103.770330335144;22353.329400118022;22422.878856057294;22444.77555224443;22558.66162007555;22602.77397226023;2173.815225705817;2252.7859853313907;2262.5022495051226;2328.7671232876746;2694.8383097014244;13223.73552528944;14549.418023279057;14618.780718289234;14847.183404912725;15225.010374325633;1813.0147488500072;1894.6400183965002;1901.9904900475547;1927.1616846671602;1928.2163998301514;59656.403435965956;61133.38866611321;62362;76886.61556692237;77738.6113069431;7981.800454988555;9870.555824987892;11016.449177541252;11186.944065279622;11439.656810295704;14630.122192668474;14703.92648036753;14733.778993315109;14740.926295368454;14752.778708319383;7711.614419279013;7752.263534964282;7874.094479134858;7948.602569871483;8001.559914204775;7338.633068346561;7347.228541003225;7372.373348265458;7622.809429764189;7677.385809135256;6293.276273228637;6387.169667943138;6420.390062944013;6544.44372228366;6589.703463344161;1857.6099019206117;1922.2983610982171;1926.7687877454796;1934.5840644261552;1939.4084804134875;876.1676407412983;948.9798466648243;952.8237276103804;1014.6758480183285;1015.669907280137;17526.737098943526;18508.074596270133;18530.258789648262;19977.700334494995;21003.584587772602;974.8050389922043;975.9902400976041;982.1749730226501;1013.083159740436;1051.4952822645173;9587.568559414844;10901.1279097672;10910.5090270938;10916.524225067684;11010.889891101147;476418;476481;477375.6787296387;479925;486063;88436.5578172105;89970.55014725015;90930;90998.54500727454;91103.54448227717;919.8114386550875;963.4460185393286;1002.326115387305;1040.141882946557;1058.6612284069024;17161.82838171652;17296.91351543234;17888.105594720473;18048.187831547348;18293.725594115833;40542.39186412147;41133.17733645251;42082.1583568333;42771.57228427676;43898.78050609727;56916;57382.42617573771;57654;58957.705211473665;59352;8108.148644392254;8229.79425514367;8293.626786794488;8407.2433480987;8483.575821208791;10907.618233361794;12846.293453860128;12868.935655321664;12895.742085158432;13467.3939672713;11274.210805243554;11309.491072901574;11383.203175777617;11671.299722016705;11718.531258749554;11135.498902549567;11420.714482138013;11475.540978360772;11509.482073306554;11525.88474115248;11389.943050284695;11545.82681259765;11823.054155667354;11943;12116.091293152873;7067.82330441743;7352.595607241653;7495.475316727777;8315.417920745489;8346.624401901807;8296;10277.331973421704;10870.184736144663;10970.122390208604;11874.881251187378 22:47:20 INFO - PROCESS | 8419 | |3;mootools.html;1476.2618690654672;1491.5749011531711;1649.6948064608048;1731.264212709598;1774.4942691332972;7988.840223195532;8238.052623948248;8427.620757065932;8897.15477029682;8929.91070089299;8343.666253349875;8701.30389568833;8702.260307873823;8919.910800891988;8995.775105622362;2328.4178955261186;2361.6103342948477;2367.538330025646;2377.679013333204;2383.7735415135535;2052.7126202331688;2314.6875171851752;2332.9766702333013;2383.2373640435067;2383.9880800595975;2035.5725297687504;2168.447046003267;2236.552689462114;2243.663450482424;2246.8764217968005;2201.515666553356;2204.162418281051;2215.667649852519;2223.8776867272295;2244.9214277500373;2278.7721227877078;2292.358139720864;2292.5414917016665;2301.033565902325;2303.124812571229;8732.476051436934;8872.157145071207;8905.866412003825;8911.509866957314;8974.14745599167;5037.395512538482;5109.335786347789;5167.431582525919;5198.9480105198845;5217.295665085241;3295.96704032959;3389.254364039907;3492.5983511895947;3517.384457719889;3586.318599466093;1126.1497569335181;1181.8227265910095;1226.8282440458343;1230.274138258423;2162.740471143473;11954.163208575403;12413;12471.875281247163;12490;12606.558770443082;4429.335599660077;4520.796564154555;4534.977325113419;4556.498785133699;4580.312953057001;9792.265580081523;9854.014598540089;9864.210863130933;9871.210303175872;9883.209343252522;5189.714565698847;5198.298229739013;5202.921956170585;5208.348956380452;5216.843494695166;4810.42274927011;4811.807527698923;4820.517948205152;4869.561739443467;4890.535399137077;3517.751198324558;3591.838367273479;3669.1744357519347;3781.905452363658;3790.298794722985;1074.4949873559415;1144.079016391796;1146.5241924601364;1166.3410173252084;1166.9591564295208;842.2672275120684;889.2752406788446;936.765808547863;949.7673070097732;967.086103632075;5835;5868.530517558585;5898.675572843449;5913.113033045078;6085.482733967662;917.9678711245074;928.4522131942186;937.2970272295778;962.5091203486272;962.7496850818698;3939.763614183101;4044.2518134145275;4088;4164.437800896901;4165.708400412002;10873.565057397616;11216.439178041066;11248.662540123809;11265;11310.09519238459;11042.779144417227;11126;11136.610218642309;11142.721431964266;11165.83251251232;7876.606169691492;8117.918820811717;8241.587920604066;8250.793730156794;8258.25675689191;5853.795117170878;5987.970060149845;6070.423309785563;6123.173371594778;6249.593776404519;10268.127209187303;10313.1233845124;10315.638952636622;10326.896731032593;10407.271490995861;9347;9569;9621.759456013655;9897.901020989699;9904.950475247577;7720.884186737091;7898.881516777138;7916.08964969036;7972.322352600094;7972.920270797218;8517.95741021291;8526.147385261424;8573.957130214309;8651.567421629019;8701.956490217508;7732.536047837148;7795;7801.843963120819;7819.178986206366;8071.07182674;7521.661525231269;7528.4353673473615;7641.7707468776025;7680.385569154342;7791.92208077912;7211.963940180265;7215.71137154508;7259.003773962203;7299.233580474186;7317.15852676949;4974.726390048582;4978.253262010728;5088.363954505687;5092.821751238689;5186.299849520266;4965.677230979974;5073.720945348041;5182.844514664566;5185.533302002837;5235 22:47:20 INFO - PROCESS | 8419 | |4;prototype.html;309.8636599896044;316.1968599756618;322.3842460899682;323.24522240568274;324.063456610396;5262.079136151177;5384.311746453682;5453.863653408666;5463.535599474045;5464.169503216427;5251.212318152279;5335.573154147669;5399.082156033474;5434.3207099112615;5447.727613619323;4235.369382787618;4247.490301163864;4260.659147268227;4267.295896177127;4274.914501709964;4175.498940127189;4183.853565125221;4207.768572728499;4284.164587905359;4285.485741710999;4175.707700460969;4177.180403227824;4184.246835569596;4190.407645094875;4191.853285135021;4177.7284476509085;4180.393842892779;4214.808299684178;4219.472565929259;4221.767802770846;3710.7216958728204;3714.888553343404;3730.1047748540154;3730.5150330457136;3732.2908647356917;5057.317262169597;5224.373075230958;5266.368035835724;5282.154855223184;5294.470552944713;4724.480307166209;4756.573028091559;4767.594754445876;4770.7376094314795;4803.711777293373;5389.649672771257;5623.578231632603;5642.943570564283;5645.463680950304;5653.9151912721345;3948.368261078214;3959.742616729903;3964.5242570891387;3968.603139686037;3973.3443981742976;5849.883002339972;5856.297244330665;5860.882782344372;5863.5016023638045;5882.147088672119;3999.0402303446963;4065.6747460203114;4089.8364065437645;4091.324931386288;4094.5905409458815;4104.363823607346;4108;4112.712110152261;4114.115465175002;4127.772972486482;4704.858854234379;4728.345079222314;4730.858074257778;4735;4737.502562230985;5566.332040155207;5651.519620832275;5654.264945557051;5666.603337766399;5673.262475878192;1414.8585141485976;1416.6600015996294;1423.4235134770433;1432.6919712261913;1436.5331267338156;872.9869051964225;883.8642344587174;888.3515034025198;896.87892134561;904.9638014479347;4792.808287668454;4811.099615651798;4815.422149342031;4822.132016237111;4867.756612169377;5069.8592816615965;5081.2632168335385;5081.568066714371;5114.897702045938;5190.780166660832;4819.927701084486;4830.420349558076;4838.419389673261;4845.878853028632;5149.6910185389;3054.9083527493767;3091.335362896988;3094.6538255858773;3097.3495565930957;3104.674009229044;5984.977586318968;6023.6145686492355;6023.69881505923;6046.879062418727;6049.727762250709;5641.689707066151;5927.318358388754;5937.406259374028;5979.820605381846;5985.191999080069;6000.33996260411;6034.426729460694;6041.78853740117;6063.575549711478;6095.51235901118;5601.80393686219;5871.295444546682;5896.911546326723;5915.467607915309;5917.201177840936;5721.370649228665;5739.971300143473;5748.48263656273;5814.418558144152;5848.853778655568;6002.909956350571;6012.128241405059;6021.7591296347655;6027.517798576016;6030.547708921936;3879.961200388073;4038.7374820636564;4055.209234199303;4060.7766572838777;4062.431259623596;5771.971140144273;5777.191193232867;5799.159121927381;5809.767609295581;5817;5544.61187716856;5671.149327600893;5700.230468886647;5718.228039214653;5743.253377060955;5692.203091567267;5696.772129114789;5714.428557144252;5722.5421966241765;5745.741441635053;5524.254225679642;5528;5573.804916827891;5574.554035677055;5619.438056194348;5482.17767334903;5663.752185860604;5670.943290567042;5713.885792270467;5729.8567535811935;5564.8330550083565;5571.8885622288135;5576.358718747396;5598;5604.159376093456 22:47:20 INFO - PROCESS | 8419 | |5;yui.html;728.7376544443999;768.8885111658809;791.6516732637639;795.904491461024;825.7233826668067;13401.1959282443;13934.51229206978;13979.510717124902;14033.859661403396;14219.146851188923;12562.120651554394;12666.493340266401;12670.23978561287;12671.113022088457;12689;641.1056576076371;661.9172603424572;776.9533827970312;786.6460092958163;796.6574373019599;791.9168487308837;795.4948607634165;796.8725003999361;800.8118092248317;800.9799755006143;848.5121055393142;855.2815634866711;855.2815634866711;855.670566831768;855.8801767752519;683.3849535418113;701.1726163127533;818.3616778912458;835.381817455085;840.0633293877282;609.5458883132051;609.7530500147448;610.2859654204591;610.4201009041428;610.6305685060519;13166.934165329112;13252.668683282898;13300.667483312995;13314.533991310354;13350.332483375792;12800.231986080866;12974.610761677164;13330.200187988654;13536.120152190175;13640.727185456335;12536.373181340987;12710.364481775874;12913.225206487547;13081.149725267922;13265.403056862464;4345.392204884213;4390.429244198266;4442.8667139985855;4467.932981005288;4487.259602165639;61542.69228653873;61796.99999999955;62310.37689623091;62511.37488625146;64332.67833660802;10683.57265709363;10700.090492308242;10811.513481893333;11028.22802403824;11095.334279943228;17798.733019004725;17875.37436189727;17958.371456998942;18634.34779782701;19413.708794368096;7468.290512401313;7541.509801862861;7924.4056695746895;7956.880646790303;8063.233992770677;4339.175556644227;4408.162449134654;4487.147441986012;4541.205289074399;4667.019925815549;3403.1662242750685;3483.4426491761196;3515.8593656253975;3519.7536172468194;3521.9471707924404;558.4052983572483;561.6545824318015;565.7058329668549;585.4175095779726;604.1360853978765;588.8086570786263;695.0824911117277;698.6960672107649;709.4041005555358;741.22541943669;8981.640734370683;9287.90712092884;9555.474448905237;9663.758406039906;9683.515824208762;602.6625089949642;710.204570880617;733.4865594084056;755.6033082631662;773.4044785515122;4470.374147619336;4726.787294571753;4811.879703007383;4828.927566086511;4841.225403935354;54179.45820541744;61944;75258.24741752622;83332.2501137384;88721.11278887128;46404.76797615991;68214.3178568208;77509.61245193738;77741.61129194431;78831.60584197042;66707;69722.65138674274;70538.2946170542;70654.2934570658;71676.283237168;9414;9816.705498835046;9825.508724563886;9916.851247231152;9979.505123719071;40725.981850452794;40926.59073409228;41723.7913810429;41988;42643.147137057706;57101.71449142728;57897.421025790885;59350;59566.40433595782;60749.39250607437;12055.81916271239;12169.148159628741;12373.876261237632;12381.75236495283;12524.812127817908;12891;13012.8048079277;13095;13100.737985240052;13292.069555131047;12173.69565760863;12261.141720079866;12377.938110309751;12732.172408793398;13256.53602123921;13296.800547991594;13451.529196478075;13503.72992540124;13600.183988960694;13736.65658358509;12627.305498197687;12629.747405051663;12758.234505929673;13338.13302135358;13380.799288010492;10145.28982971185;10258.538365773707;10398;10487.423191724529;11506.252093613888;10524.473776311;11128.332300062022;11324.207305488537;11341.489632966372;11435.313881167156 22:47:20 INFO - PROCESS | 8419 | __end_tp_report 22:47:20 INFO - PROCESS | 8419 | __start_cc_report 22:47:20 INFO - PROCESS | 8419 | _x_x_mozilla_cycle_collect,981 22:47:20 INFO - PROCESS | 8419 | __end_cc_report 22:47:20 INFO - PROCESS | 8419 | __startTimestamp1477547240748__endTimestamp 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | ------- Summary: start ------- 22:47:20 INFO - PROCESS | 8419 | Number of tests: 6 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#0] dojo.html Cycles:165 Average:5316.18 Median:5497.56 stddev:592.41 (10.8%) stddev-sans-first:566.31 22:47:20 INFO - PROCESS | 8419 | Values: 3025.4 3084.4 3112.0 3130.9 3132.1 5271.7 5285.2 5349.7 5372.1 5382.0 5333.5 5399.1 5403.5 5405.6 5475.6 4844.7 4915.8 4923.5 4945.1 4960.7 4937.3 4939.6 5020.4 5025.2 5038.1 4971.9 5002.3 5015.7 5022.1 5076.9 4827.7 4870.9 4872.2 4898.0 4917.8 4821.5 4838.8 4847.1 4861.4 4875.8 5306.7 5332.3 5382.5 5400.5 5419.2 5714.1 5730.7 5732.2 5738.5 5741.3 5574.1 5597.0 5774.4 5775.4 5786.4 5293.4 5294.8 5296.3 5296.5 5296.7 5708.5 5711.6 5717.9 5720.8 5753.7 5621.4 5653.6 5726.9 5732.9 5735.9 5750.4 5757.8 5758.7 5762.5 5762.7 5595.5 5696.7 5697.4 5702.9 5711.9 5695.0 5755.2 5756.7 5758.4 5765.3 4367.1 4368.2 4369.7 4370.2 4376.9 3836.8 3890.1 3901.6 3901.7 3954.9 5349.1 5350.8 5353.1 5378.3 5402.9 5690.2 5691.7 5748.9 5757.9 5813.4 5248.8 5334.3 5367.2 5378.3 5394.8 4963.8 4963.9 4964.3 4965.6 5015.7 5873.5 5926.4 5927.1 5932.8 5996.1 5606.1 5659.7 5681.5 5708.6 5746.7 5940.1 5940.1 5946.7 5967.3 6008.7 5611.7 5614.7 5638.7 5657.3 5667.3 5510.9 5575.6 5632.6 5735.6 5739.2 5539.3 5571.5 5574.5 5607.8 5639.6 5517.2 5542.5 5563.4 5576.6 5580.2 5372.5 5428.4 5433.0 5497.6 5568.9 5500.6 5605.0 5623.9 5625.0 5685.4 5469.5 5472.3 5479.6 5484.1 5569.4 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#1] ext.html Cycles:180 Average:54683.04 Median:35624.69 stddev:104516.30 (293.4%) stddev-sans-first:104730.57 22:47:20 INFO - PROCESS | 8419 | Values: 583.5 617.5 625.7 642.8 647.7 31734.5 42284.4 42784.0 43044.4 46386.3 43709.8 44034.1 44050.8 44069.8 44127.0 8732.1 8929.3 8931.8 8934.0 8934.5 8004.8 8203.6 10391.2 10634.8 10641.0 10840.9 10874.2 10884.9 10891.2 10909.2 10691.7 10741.4 10749.9 10750.6 10827.3 10660.8 10730.1 10735.1 10737.2 10745.1 42414.2 42789.1 42797.4 42828.0 42844.8 3457.3 3708.0 4109.4 4145.2 4179.4 28949.9 31572.0 31677.5 31969.2 32030.7 4289.2 4354.1 4484.5 4659.9 4670.9 340242.2 344856.0 345990.0 346155.0 349834.0 32568.0 33582.7 58566.1 58944.2 59028.4 35460.3 35517.5 35558.8 35600.6 35651.3 13984.4 14195.6 14206.2 14208.3 14212.5 10030.6 10269.6 11636.1 11867.0 11867.8 7974.9 8180.6 8227.0 8231.6 8298.8 3167.1 3177.3 3186.3 3215.9 3236.7 971.1 998.2 1002.0 1035.0 1067.5 31892.2 32982.8 33571.2 35608.1 35641.3 1052.3 1096.6 1106.2 1114.5 1143.4 16041.8 16986.6 17005.5 17089.0 17101.7 455840.0 459402.0 460021.6 460063.0 460151.0 56462.4 56845.7 56866.4 56945.3 56965.1 1217.7 1233.5 1305.3 1339.3 1346.4 30043.2 32173.7 33087.5 33160.8 33711.7 49091.3 49743.5 49783.0 49799.3 49861.3 370819.0 372766.0 372853.0 375362.0 375406.0 26748.2 27590.3 27760.2 28729.6 28971.3 42998.8 45854.8 45871.1 46131.3 46254.8 39301.0 39377.0 39555.8 39560.5 41948.8 39537.8 40061.2 40141.6 40180.0 41827.8 40141.0 40646.0 40898.6 41482.8 41655.4 34103.3 35308.3 35386.5 35728.3 35767.3 27516.6 27712.6 27778.9 27952.9 27986.0 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#2] jquery.html Cycles:180 Average:28255.23 Median:385.54 stddev:78845.28 (20450.5%) stddev-sans-first:79038.65 22:47:20 INFO - PROCESS | 8419 | Values: 366.3 369.6 383.9 387.2 415.4 14238.1 18309.6 19395.2 20196.9 20459.2 18364.4 18932.9 19096.1 19105.5 19141.4 5589.4 5792.6 5848.6 5855.1 5862.6 5626.5 5685.5 5711.3 5740.3 5780.6 5694.7 5739.5 5740.3 5743.5 5744.2 5567.2 5651.8 5717.4 5745.5 5764.1 5092.7 5097.8 5101.2 5101.6 5103.8 22353.3 22422.9 22444.8 22558.7 22602.8 2173.8 2252.8 2262.5 2328.8 2694.8 13223.7 14549.4 14618.8 14847.2 15225.0 1813.0 1894.6 1902.0 1927.2 1928.2 59656.4 61133.4 62362.0 76886.6 77738.6 7981.8 9870.6 11016.4 11186.9 11439.7 14630.1 14703.9 14733.8 14740.9 14752.8 7711.6 7752.3 7874.1 7948.6 8001.6 7338.6 7347.2 7372.4 7622.8 7677.4 6293.3 6387.2 6420.4 6544.4 6589.7 1857.6 1922.3 1926.8 1934.6 1939.4 876.2 949.0 952.8 1014.7 1015.7 17526.7 18508.1 18530.3 19977.7 21003.6 974.8 976.0 982.2 1013.1 1051.5 9587.6 10901.1 10910.5 10916.5 11010.9 476418.0 476481.0 477375.7 479925.0 486063.0 88436.6 89970.6 90930.0 90998.5 91103.5 919.8 963.4 1002.3 1040.1 1058.7 17161.8 17296.9 17888.1 18048.2 18293.7 40542.4 41133.2 42082.2 42771.6 43898.8 56916.0 57382.4 57654.0 58957.7 59352.0 8108.1 8229.8 8293.6 8407.2 8483.6 10907.6 12846.3 12868.9 12895.7 13467.4 11274.2 11309.5 11383.2 11671.3 11718.5 11135.5 11420.7 11475.5 11509.5 11525.9 11389.9 11545.8 11823.1 11943.0 12116.1 7067.8 7352.6 7495.5 8315.4 8346.6 8296.0 10277.3 10870.2 10970.1 11874.9 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#3] mootools.html Cycles:180 Average:5791.11 Median:5090.59 stddev:3307.87 (65.0%) stddev-sans-first:3301.26 22:47:20 INFO - PROCESS | 8419 | Values: 1476.3 1491.6 1649.7 1731.3 1774.5 7988.8 8238.1 8427.6 8897.2 8929.9 8343.7 8701.3 8702.3 8919.9 8995.8 2328.4 2361.6 2367.5 2377.7 2383.8 2052.7 2314.7 2333.0 2383.2 2384.0 2035.6 2168.4 2236.6 2243.7 2246.9 2201.5 2204.2 2215.7 2223.9 2244.9 2278.8 2292.4 2292.5 2301.0 2303.1 8732.5 8872.2 8905.9 8911.5 8974.1 5037.4 5109.3 5167.4 5198.9 5217.3 3296.0 3389.3 3492.6 3517.4 3586.3 1126.1 1181.8 1226.8 1230.3 2162.7 11954.2 12413.0 12471.9 12490.0 12606.6 4429.3 4520.8 4535.0 4556.5 4580.3 9792.3 9854.0 9864.2 9871.2 9883.2 5189.7 5198.3 5202.9 5208.3 5216.8 4810.4 4811.8 4820.5 4869.6 4890.5 3517.8 3591.8 3669.2 3781.9 3790.3 1074.5 1144.1 1146.5 1166.3 1167.0 842.3 889.3 936.8 949.8 967.1 5835.0 5868.5 5898.7 5913.1 6085.5 918.0 928.5 937.3 962.5 962.7 3939.8 4044.3 4088.0 4164.4 4165.7 10873.6 11216.4 11248.7 11265.0 11310.1 11042.8 11126.0 11136.6 11142.7 11165.8 7876.6 8117.9 8241.6 8250.8 8258.3 5853.8 5988.0 6070.4 6123.2 6249.6 10268.1 10313.1 10315.6 10326.9 10407.3 9347.0 9569.0 9621.8 9897.9 9905.0 7720.9 7898.9 7916.1 7972.3 7972.9 8518.0 8526.1 8574.0 8651.6 8702.0 7732.5 7795.0 7801.8 7819.2 8071.1 7521.7 7528.4 7641.8 7680.4 7791.9 7212.0 7215.7 7259.0 7299.2 7317.2 4974.7 4978.3 5088.4 5092.8 5186.3 4965.7 5073.7 5182.8 5185.5 5235.0 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#4] prototype.html Cycles:180 Average:4707.90 Median:5288.31 stddev:1399.04 (26.5%) stddev-sans-first:1363.46 22:47:20 INFO - PROCESS | 8419 | Values: 309.9 316.2 322.4 323.2 324.1 5262.1 5384.3 5453.9 5463.5 5464.2 5251.2 5335.6 5399.1 5434.3 5447.7 4235.4 4247.5 4260.7 4267.3 4274.9 4175.5 4183.9 4207.8 4284.2 4285.5 4175.7 4177.2 4184.2 4190.4 4191.9 4177.7 4180.4 4214.8 4219.5 4221.8 3710.7 3714.9 3730.1 3730.5 3732.3 5057.3 5224.4 5266.4 5282.2 5294.5 4724.5 4756.6 4767.6 4770.7 4803.7 5389.6 5623.6 5642.9 5645.5 5653.9 3948.4 3959.7 3964.5 3968.6 3973.3 5849.9 5856.3 5860.9 5863.5 5882.1 3999.0 4065.7 4089.8 4091.3 4094.6 4104.4 4108.0 4112.7 4114.1 4127.8 4704.9 4728.3 4730.9 4735.0 4737.5 5566.3 5651.5 5654.3 5666.6 5673.3 1414.9 1416.7 1423.4 1432.7 1436.5 873.0 883.9 888.4 896.9 905.0 4792.8 4811.1 4815.4 4822.1 4867.8 5069.9 5081.3 5081.6 5114.9 5190.8 4819.9 4830.4 4838.4 4845.9 5149.7 3054.9 3091.3 3094.7 3097.3 3104.7 5985.0 6023.6 6023.7 6046.9 6049.7 5641.7 5927.3 5937.4 5979.8 5985.2 6000.3 6034.4 6041.8 6063.6 6095.5 5601.8 5871.3 5896.9 5915.5 5917.2 5721.4 5740.0 5748.5 5814.4 5848.9 6002.9 6012.1 6021.8 6027.5 6030.5 3880.0 4038.7 4055.2 4060.8 4062.4 5772.0 5777.2 5799.2 5809.8 5817.0 5544.6 5671.1 5700.2 5718.2 5743.3 5692.2 5696.8 5714.4 5722.5 5745.7 5524.3 5528.0 5573.8 5574.6 5619.4 5482.2 5663.8 5670.9 5713.9 5729.9 5564.8 5571.9 5576.4 5598.0 5604.2 22:47:20 INFO - PROCESS | 8419 | 22:47:20 INFO - PROCESS | 8419 | [#5] yui.html Cycles:180 Average:16869.98 Median:25535.89 stddev:21749.34 (85.2%) stddev-sans-first:21776.58 22:47:20 INFO - PROCESS | 8419 | Values: 728.7 768.9 791.7 795.9 825.7 13401.2 13934.5 13979.5 14033.9 14219.1 12562.1 12666.5 12670.2 12671.1 12689.0 641.1 661.9 777.0 786.6 796.7 791.9 795.5 796.9 800.8 801.0 848.5 855.3 855.3 855.7 855.9 683.4 701.2 818.4 835.4 840.1 609.5 609.8 610.3 610.4 610.6 13166.9 13252.7 13300.7 13314.5 13350.3 12800.2 12974.6 13330.2 13536.1 13640.7 12536.4 12710.4 12913.2 13081.1 13265.4 4345.4 4390.4 4442.9 4467.9 4487.3 61542.7 61797.0 62310.4 62511.4 64332.7 10683.6 10700.1 10811.5 11028.2 11095.3 17798.7 17875.4 17958.4 18634.3 19413.7 7468.3 7541.5 7924.4 7956.9 8063.2 4339.2 4408.2 4487.1 4541.2 4667.0 3403.2 3483.4 3515.9 3519.8 3521.9 558.4 561.7 565.7 585.4 604.1 588.8 695.1 698.7 709.4 741.2 8981.6 9287.9 9555.5 9663.8 9683.5 602.7 710.2 733.5 755.6 773.4 4470.4 4726.8 4811.9 4828.9 4841.2 54179.5 61944.0 75258.2 83332.3 88721.1 46404.8 68214.3 77509.6 77741.6 78831.6 66707.0 69722.7 70538.3 70654.3 71676.3 9414.0 9816.7 9825.5 9916.9 9979.5 40726.0 40926.6 41723.8 41988.0 42643.1 57101.7 57897.4 59350.0 59566.4 60749.4 12055.8 12169.1 12373.9 12381.8 12524.8 12891.0 13012.8 13095.0 13100.7 13292.1 12173.7 12261.1 12377.9 12732.2 13256.5 13296.8 13451.5 13503.7 13600.2 13736.7 12627.3 12629.7 12758.2 13338.1 13380.8 10145.3 10258.5 10398.0 10487.4 11506.3 10524.5 11128.3 11324.2 11341.5 11435.3 22:47:20 INFO - PROCESS | 8419 | -------- Summary: end -------- 22:47:20 INFO - PROCESS | 8419 | 22:47:21 INFO - TEST-INFO | 8419: exit 0 22:47:21 INFO - TEST-OK | dromaeo_css | took 1116855ms 22:47:21 INFO - TEST-START | kraken 22:47:21 INFO - operating with platform_type : linux_ 22:47:21 INFO - Initialising browser for kraken test... 22:47:21 INFO - TEST-INFO | started process 8725 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeh_eKt/profile http://localhost:35751/getInfo.html) 22:47:25 INFO - PROCESS | 8725 | __metrics Screen width/height:1600/1200 22:47:25 INFO - PROCESS | 8725 | colorDepth:24 22:47:25 INFO - PROCESS | 8725 | Browser inner width/height: 1024/697 22:47:25 INFO - PROCESS | 8725 | __metrics 22:47:26 INFO - PROCESS | 8725 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:47:26 INFO - PROCESS | 8725 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:47:26 INFO - PROCESS | 8725 | console.error: PushService: 22:47:26 INFO - PROCESS | 8725 | stateChangeProcessEnqueue: Error transitioning state 22:47:26 INFO - PROCESS | 8725 | UnknownError 22:47:27 INFO - TEST-INFO | 8725: exit 0 22:47:27 INFO - Browser initialized. 22:47:27 INFO - Running cycle 1/1 for kraken test... 22:47:27 INFO - TEST-INFO | started process 8808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeh_eKt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:47:39 INFO - PROCESS | 8808 | RSS: Main: 172433408 22:47:39 INFO - PROCESS | 8808 | 22:47:42 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35751/tests/kraken/driver.html?testName=audio-beat-detection) 22:47:43 INFO - PROCESS | 8808 | RSS: Main: 229408768 22:47:43 INFO - PROCESS | 8808 | 22:47:45 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35751/tests/kraken/driver.html?testName=audio-dft) 22:47:46 INFO - PROCESS | 8808 | RSS: Main: 215715840 22:47:46 INFO - PROCESS | 8808 | 22:47:49 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35751/tests/kraken/driver.html?testName=audio-fft) 22:47:50 INFO - PROCESS | 8808 | RSS: Main: 296865792 22:47:50 INFO - PROCESS | 8808 | 22:47:52 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35751/tests/kraken/driver.html?testName=audio-oscillator) 22:47:52 INFO - PROCESS | 8808 | RSS: Main: 208949248 22:47:52 INFO - PROCESS | 8808 | 22:47:54 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:47:55 INFO - PROCESS | 8808 | RSS: Main: 211582976 22:47:55 INFO - PROCESS | 8808 | 22:47:58 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35751/tests/kraken/driver.html?testName=imaging-darkroom) 22:47:59 INFO - PROCESS | 8808 | RSS: Main: 247910400 22:47:59 INFO - PROCESS | 8808 | 22:48:02 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35751/tests/kraken/driver.html?testName=imaging-desaturate) 22:48:02 INFO - PROCESS | 8808 | RSS: Main: 246501376 22:48:02 INFO - PROCESS | 8808 | 22:48:05 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35751/tests/kraken/driver.html?testName=json-parse-financial) 22:48:06 INFO - PROCESS | 8808 | RSS: Main: 245923840 22:48:06 INFO - PROCESS | 8808 | 22:48:07 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:48:08 INFO - PROCESS | 8808 | RSS: Main: 208977920 22:48:08 INFO - PROCESS | 8808 | 22:48:10 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:48:10 INFO - PROCESS | 8808 | RSS: Main: 216625152 22:48:10 INFO - PROCESS | 8808 | 22:48:12 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:48:13 INFO - PROCESS | 8808 | RSS: Main: 225517568 22:48:13 INFO - PROCESS | 8808 | 22:48:15 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:48:16 INFO - PROCESS | 8808 | RSS: Main: 234209280 22:48:16 INFO - PROCESS | 8808 | 22:48:19 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:48:20 INFO - PROCESS | 8808 | RSS: Main: 230494208 22:48:20 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | Cycle 1(1): loaded http://localhost:35751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35751/tests/kraken/driver.html?testName=ai-astar) 22:48:21 INFO - PROCESS | 8808 | RSS: Main: 279961600 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | __start_tp_report 22:48:21 INFO - PROCESS | 8808 | _x_x_mozilla_page_load 22:48:21 INFO - PROCESS | 8808 | _x_x_mozilla_page_load_details 22:48:21 INFO - PROCESS | 8808 | |i|pagename|runs| 22:48:21 INFO - PROCESS | 8808 | |0;ai-astar;103;111;110;110;111;111;110;109;109;110 22:48:21 INFO - PROCESS | 8808 | |1;audio-beat-detection;149;123;122;158;123;125;122;122;160;125 22:48:21 INFO - PROCESS | 8808 | |2;audio-dft;162;160;160;160;160;161;161;162;160;159 22:48:21 INFO - PROCESS | 8808 | |3;audio-fft;76;75;76;111;75;76;77;75;99;76 22:48:21 INFO - PROCESS | 8808 | |4;audio-oscillator;82;82;122;89;81;82;105;81;82;109 22:48:21 INFO - PROCESS | 8808 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;117 22:48:21 INFO - PROCESS | 8808 | |6;imaging-darkroom;146;145;146;146;146;145;146;146;145;147 22:48:21 INFO - PROCESS | 8808 | |7;imaging-desaturate;85;85;84;85;85;85;85;84;84;85 22:48:21 INFO - PROCESS | 8808 | |8;json-parse-financial;55;54;54;54;55;59;57;55;56;56 22:48:21 INFO - PROCESS | 8808 | |9;json-stringify-tinderbox;61;61;61;62;61;61;61;61;61;62 22:48:21 INFO - PROCESS | 8808 | |10;stanford-crypto-aes;79;80;81;79;84;79;87;87;79;79 22:48:21 INFO - PROCESS | 8808 | |11;stanford-crypto-ccm;133;136;140;131;129;132;134;133;177;135 22:48:21 INFO - PROCESS | 8808 | |12;stanford-crypto-pbkdf2;165;169;167;172;165;165;171;178;166;168 22:48:21 INFO - PROCESS | 8808 | |13;stanford-crypto-sha256-iterative;65;65;64;66;65;64;65;68;65;65 22:48:21 INFO - PROCESS | 8808 | __end_tp_report 22:48:21 INFO - PROCESS | 8808 | __start_cc_report 22:48:21 INFO - PROCESS | 8808 | _x_x_mozilla_cycle_collect,1702 22:48:21 INFO - PROCESS | 8808 | __end_cc_report 22:48:21 INFO - PROCESS | 8808 | __startTimestamp1477547301974__endTimestamp 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | ------- Summary: start ------- 22:48:21 INFO - PROCESS | 8808 | Number of tests: 14 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:2.37 (2.2%) stddev-sans-first:0.78 22:48:21 INFO - PROCESS | 8808 | Values: 103.0 111.0 110.0 110.0 111.0 111.0 110.0 109.0 109.0 110.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:125.00 stddev:15.99 (12.8%) stddev-sans-first:15.86 22:48:21 INFO - PROCESS | 8808 | Values: 149.0 123.0 122.0 158.0 123.0 125.0 122.0 122.0 160.0 125.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:0.97 (0.6%) stddev-sans-first:0.87 22:48:21 INFO - PROCESS | 8808 | Values: 162.0 160.0 160.0 160.0 160.0 161.0 161.0 162.0 160.0 159.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:12.67 (16.7%) stddev-sans-first:13.27 22:48:21 INFO - PROCESS | 8808 | Values: 76.0 75.0 76.0 111.0 75.0 76.0 77.0 75.0 99.0 76.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:82.00 stddev:14.93 (18.2%) stddev-sans-first:15.44 22:48:21 INFO - PROCESS | 8808 | Values: 82.0 82.0 122.0 89.0 81.0 82.0 105.0 81.0 82.0 109.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:48:21 INFO - PROCESS | 8808 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:48:21 INFO - PROCESS | 8808 | Values: 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 147.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 22:48:21 INFO - PROCESS | 8808 | Values: 85.0 85.0 84.0 85.0 85.0 85.0 85.0 84.0 84.0 85.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:1.58 (2.8%) stddev-sans-first:1.67 22:48:21 INFO - PROCESS | 8808 | Values: 55.0 54.0 54.0 54.0 55.0 59.0 57.0 55.0 56.0 56.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 22:48:21 INFO - PROCESS | 8808 | Values: 61.0 61.0 61.0 62.0 61.0 61.0 61.0 61.0 61.0 62.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:80.50 stddev:3.34 (4.1%) stddev-sans-first:3.43 22:48:21 INFO - PROCESS | 8808 | Values: 79.0 80.0 81.0 79.0 84.0 79.0 87.0 87.0 79.0 79.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:134.50 stddev:14.02 (10.4%) stddev-sans-first:14.76 22:48:21 INFO - PROCESS | 8808 | Values: 133.0 136.0 140.0 131.0 129.0 132.0 134.0 133.0 177.0 135.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:168.50 stddev:4.14 (2.5%) stddev-sans-first:4.18 22:48:21 INFO - PROCESS | 8808 | Values: 165.0 169.0 167.0 172.0 165.0 165.0 171.0 178.0 166.0 168.0 22:48:21 INFO - PROCESS | 8808 | 22:48:21 INFO - PROCESS | 8808 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 22:48:21 INFO - PROCESS | 8808 | Values: 65.0 65.0 64.0 66.0 65.0 64.0 65.0 68.0 65.0 65.0 22:48:21 INFO - PROCESS | 8808 | -------- Summary: end -------- 22:48:21 INFO - PROCESS | 8808 | 22:48:23 INFO - TEST-INFO | 8808: exit 0 22:48:23 INFO - TEST-OK | kraken | took 61579ms 22:48:23 INFO - SUITE-END | took 1178s 22:48:23 INFO - Completed test suite (00:19:38) 22:48:23 INFO - javascript benchmark 22:48:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3025.364673418582, 3084.4447999360136, 3112.0, 3130.921726956827, 3132.1386618679844, 5271.65734227275, 5285.233641122039, 5349.652272602279, 5372.113601255798, 5381.973090134554, 5333.54664853487, 5399.055165346068, 5403.459654034595, 5405.648632838863, 5475.6440831345935, 4844.685095468801, 4915.754212289389, 4923.507649235065, 4945.085159245542, 4960.727160006199, 4937.33345998291, 4939.555440010398, 5020.39755229373, 5025.246213068032, 5038.067957427869, 4971.900561988776, 5002.349694539705, 5015.749212539358, 5022.121128802463, 5076.8730781730565, 4827.710337379768, 4870.902581948377, 4872.171730805736, 4898.0, 4917.754112294371, 4821.517848215186, 4838.830640927586, 4847.12751704693, 4861.440934292566, 4875.804967801284, 5306.681599104066, 5332.30680011596, 5382.461753824626, 5400.459954004607, 5419.241306217133, 5714.057180565202, 5730.713464326767, 5732.168835518826, 5738.483536481736, 5741.3110426748635, 5574.052411090124, 5597.048501754712, 5774.364819869811, 5775.422457754233, 5786.392428794958, 5293.39126000511, 5294.7617357219015, 5296.311479507678, 5296.496832800878, 5296.735163241861, 5708.543316534668, 5711.628744131618, 5717.942820571824, 5720.799772007959, 5753.712314384264, 5621.381648018714, 5653.604247702621, 5726.914096288559, 5732.856678583069, 5735.913961290584, 5750.396208398141, 5757.769689212385, 5758.683272419973, 5762.481376676119, 5762.732198916232, 5595.524380427628, 5696.715164241771, 5697.373288938214, 5702.914456283159, 5711.942880571224, 5695.0, 5755.194272801811, 5756.675964528182, 5758.395368486333, 5765.336986246635, 4367.126574685076, 4368.2355587772645, 4369.715968462104, 4370.169667763115, 4376.868693939187, 3836.8081595920094, 3890.144168282973, 3901.5708272090046, 3901.726879118491, 3954.940675889864, 5349.090654588729, 5350.8127215547265, 5353.116735738559, 5378.273933019071, 5402.8919421611345, 5690.231818704505, 5691.715414229272, 5748.856278592985, 5757.8560535986935, 5813.418658134153, 5248.790048398021, 5334.279872217201, 5367.221752845816, 5378.273933019071, 5394.757235924393, 4963.8262660806695, 4963.851084467472, 4964.304997300381, 4965.577925876341, 5015.699058056528, 5873.471387575139, 5926.436988486087, 5927.051671732502, 5932.7923522677365, 5996.13056106862, 5606.074997625346, 5659.717014149359, 5681.517071049007, 5708.57185711065, 5746.655200687972, 5940.079287710412, 5940.138679891305, 5946.702664866826, 5967.254093238345, 6008.729607167776, 5611.691356975405, 5614.6631202128, 5638.746256418389, 5657.321121465286, 5667.319921609268, 5510.889782204414, 5575.553955683455, 5632.5775566831835, 5735.627184233012, 5739.2252045973255, 5539.307586551681, 5571.498565129158, 5574.526165275997, 5607.775688972395, 5639.577031722719, 5517.172424136412, 5542.501174894279, 5563.360213575491, 5576.637518561281, 5580.190872323572, 5372.5433338166695, 5428.375736790162, 5432.994895944263, 5497.560195184456, 5568.860778480571, 5500.5874559409, 5605.019121653776, 5623.8875222496135, 5624.971875140762, 5685.374608793112, 5469.453054694499, 5472.315960504937, 5479.6164268500825, 5484.122540393518, 5569.359523654832], "value": 5276.581242591948, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.5098517245516, 617.4874853871287, 625.6934102289879, 642.7525402719951, 647.7279542592111, 31734.523982140287, 42284.365734513965, 42784.0, 43044.354334685006, 46386.30420543686, 43709.78145109278, 44034.11931761363, 44050.7797461013, 44069.7796511017, 44127.0, 8732.126787321266, 8929.330300227477, 8931.821363572724, 8933.95533022334, 8934.508602026886, 8004.759857204293, 8203.58982050898, 10391.22065845061, 10634.78730425391, 10641.0, 10840.891591084068, 10874.18443616732, 10884.945575272151, 10891.183161262938, 10909.18181136414, 10691.732706682318, 10741.409222492759, 10749.892501074968, 10750.569977200903, 10827.296225745302, 10660.840087398696, 10730.141588672888, 10735.141188704885, 10737.24839261252, 10745.086667633183, 42414.15171696549, 42789.1442171158, 42797.35803962943, 42828.0, 42844.78577607092, 3457.3431048101033, 3707.9814600926825, 4109.445224894631, 4145.191687620915, 4179.435776170239, 28949.855250723824, 31572.0, 31677.524837127232, 31969.200769980704, 32030.679693203238, 4289.227938970984, 4354.107407981356, 4484.461864576239, 4659.860204193879, 4670.906581868344, 340242.1830471868, 344856.0, 345989.9999999975, 346155.0, 349834.0, 32568.0, 33582.66417335844, 58566.12150817741, 58944.23167304988, 59028.40971590315, 35460.29079418397, 35517.46723799093, 35558.822205888806, 35600.643993560254, 35651.28697426037, 13984.440622375194, 14195.64510887236, 14206.21865797391, 14208.28958552089, 14212.502562410473, 10030.64892728751, 10269.640562580274, 11636.12729045325, 11867.0, 11867.821982670266, 7974.920250797534, 8180.550069746221, 8226.958865205756, 8231.588420578948, 8298.751037468886, 3167.0657156138996, 3177.348643528071, 3186.2512309607223, 3215.887443939451, 3236.7248783853165, 971.0871780526282, 998.1914649134225, 1001.9899801001897, 1034.9689509314583, 1067.4662668665667, 31892.172939247437, 32982.835085824416, 33571.16072098168, 35608.083110857784, 35641.28717425689, 1052.289704449509, 1096.6107032003683, 1106.2366966792888, 1114.5263263113145, 1143.422571601336, 16041.759373609406, 16986.57533561646, 17005.48983530496, 17089.0, 17101.65796684084, 455840.0, 459402.0, 460021.59589677857, 460063.0, 460151.0, 56462.43537564572, 56845.71577142088, 56866.43133568612, 56945.29164125083, 56965.14552281719, 1217.6773155113901, 1233.4572787973264, 1305.288617703354, 1339.2634051271762, 1346.373936119709, 30043.24891877633, 32173.678263218004, 33087.50368744519, 33160.834195828866, 33711.66288337085, 49091.26363104485, 49743.50256497389, 49783.0, 49799.253011204135, 49861.252081219536, 370819.0, 372766.0, 372853.0, 375362.0, 375406.0, 26748.19755407341, 27590.310242244104, 27760.167194984184, 28729.56905646459, 28971.27571810637, 42998.785006074766, 45854.77072614749, 45871.08257834891, 46131.3080303789, 46254.76872615615, 39301.0, 39377.0, 39555.80222098871, 39560.483508133366, 41948.79025604852, 39537.80231098826, 40061.19877602373, 40141.59858401495, 40180.0, 41827.7908610455, 40141.0, 40646.0, 40898.59101408948, 41482.79258603688, 41655.37516937188, 34103.31793364169, 35308.29383412266, 35386.46920296249, 35728.28543429065, 35767.28465430729, 27516.58725119085, 27712.584311234943, 27778.86110569434, 27952.860235698692, 27986.0], "value": 18803.113202285367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.3076784876582, 369.58052610287314, 383.9232153569285, 387.1598630970793, 415.424636877924, 14238.074525155871, 18309.633807323862, 19395.224191032343, 20196.899015504903, 20459.18163273471, 18364.35724749634, 18932.905335473337, 19096.140673669684, 19105.52236194096, 19141.425757227305, 5589.385167631557, 5792.594518383715, 5848.649081055129, 5855.063189889597, 5862.618929769573, 5626.521745651625, 5685.488306052454, 5711.269148787827, 5740.339860915986, 5780.566457515703, 5694.715264236792, 5739.540836733072, 5740.311162660487, 5743.4543718346695, 5744.195812586241, 5567.164925261202, 5651.7739290428335, 5717.3710891802075, 5745.540356771448, 5764.07774756037, 5092.694438333712, 5097.847064588068, 5101.2093125565225, 5101.642884998052, 5103.770330335144, 22353.329400118022, 22422.878856057294, 22444.77555224443, 22558.66162007555, 22602.77397226023, 2173.815225705817, 2252.7859853313907, 2262.5022495051226, 2328.7671232876746, 2694.8383097014244, 13223.73552528944, 14549.418023279057, 14618.780718289234, 14847.183404912725, 15225.010374325633, 1813.0147488500072, 1894.6400183965002, 1901.9904900475547, 1927.1616846671602, 1928.2163998301514, 59656.403435965956, 61133.38866611321, 62362.0, 76886.61556692237, 77738.6113069431, 7981.800454988555, 9870.555824987892, 11016.449177541252, 11186.944065279622, 11439.656810295704, 14630.122192668474, 14703.92648036753, 14733.778993315109, 14740.926295368454, 14752.778708319383, 7711.614419279013, 7752.263534964282, 7874.094479134858, 7948.602569871483, 8001.559914204775, 7338.633068346561, 7347.228541003225, 7372.373348265458, 7622.809429764189, 7677.385809135256, 6293.276273228637, 6387.169667943138, 6420.390062944013, 6544.44372228366, 6589.703463344161, 1857.6099019206117, 1922.2983610982171, 1926.7687877454796, 1934.5840644261552, 1939.4084804134875, 876.1676407412983, 948.9798466648243, 952.8237276103804, 1014.6758480183285, 1015.669907280137, 17526.737098943526, 18508.074596270133, 18530.258789648262, 19977.700334494995, 21003.584587772602, 974.8050389922043, 975.9902400976041, 982.1749730226501, 1013.083159740436, 1051.4952822645173, 9587.568559414844, 10901.1279097672, 10910.5090270938, 10916.524225067684, 11010.889891101147, 476418.0, 476481.0, 477375.6787296387, 479925.0, 486063.0, 88436.5578172105, 89970.55014725015, 90930.0, 90998.54500727454, 91103.54448227717, 919.8114386550875, 963.4460185393286, 1002.326115387305, 1040.141882946557, 1058.6612284069024, 17161.82838171652, 17296.91351543234, 17888.105594720473, 18048.187831547348, 18293.725594115833, 40542.39186412147, 41133.17733645251, 42082.1583568333, 42771.57228427676, 43898.78050609727, 56916.0, 57382.42617573771, 57654.0, 58957.705211473665, 59352.0, 8108.148644392254, 8229.79425514367, 8293.626786794488, 8407.2433480987, 8483.575821208791, 10907.618233361794, 12846.293453860128, 12868.935655321664, 12895.742085158432, 13467.3939672713, 11274.210805243554, 11309.491072901574, 11383.203175777617, 11671.299722016705, 11718.531258749554, 11135.498902549567, 11420.714482138013, 11475.540978360772, 11509.482073306554, 11525.88474115248, 11389.943050284695, 11545.82681259765, 11823.054155667354, 11943.0, 12116.091293152873, 7067.82330441743, 7352.595607241653, 7495.475316727777, 8315.417920745489, 8346.624401901807, 8296.0, 10277.331973421704, 10870.184736144663, 10970.122390208604, 11874.881251187378], "value": 9131.951486967275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.2618690654672, 1491.5749011531711, 1649.6948064608048, 1731.264212709598, 1774.4942691332972, 7988.840223195532, 8238.052623948248, 8427.620757065932, 8897.15477029682, 8929.91070089299, 8343.666253349875, 8701.30389568833, 8702.260307873823, 8919.910800891988, 8995.775105622362, 2328.4178955261186, 2361.6103342948477, 2367.538330025646, 2377.679013333204, 2383.7735415135535, 2052.7126202331688, 2314.6875171851752, 2332.9766702333013, 2383.2373640435067, 2383.9880800595975, 2035.5725297687504, 2168.447046003267, 2236.552689462114, 2243.663450482424, 2246.8764217968005, 2201.515666553356, 2204.162418281051, 2215.667649852519, 2223.8776867272295, 2244.9214277500373, 2278.7721227877078, 2292.358139720864, 2292.5414917016665, 2301.033565902325, 2303.124812571229, 8732.476051436934, 8872.157145071207, 8905.866412003825, 8911.509866957314, 8974.14745599167, 5037.395512538482, 5109.335786347789, 5167.431582525919, 5198.9480105198845, 5217.295665085241, 3295.96704032959, 3389.254364039907, 3492.5983511895947, 3517.384457719889, 3586.318599466093, 1126.1497569335181, 1181.8227265910095, 1226.8282440458343, 1230.274138258423, 2162.740471143473, 11954.163208575403, 12413.0, 12471.875281247163, 12490.0, 12606.558770443082, 4429.335599660077, 4520.796564154555, 4534.977325113419, 4556.498785133699, 4580.312953057001, 9792.265580081523, 9854.014598540089, 9864.210863130933, 9871.210303175872, 9883.209343252522, 5189.714565698847, 5198.298229739013, 5202.921956170585, 5208.348956380452, 5216.843494695166, 4810.42274927011, 4811.807527698923, 4820.517948205152, 4869.561739443467, 4890.535399137077, 3517.751198324558, 3591.838367273479, 3669.1744357519347, 3781.905452363658, 3790.298794722985, 1074.4949873559415, 1144.079016391796, 1146.5241924601364, 1166.3410173252084, 1166.9591564295208, 842.2672275120684, 889.2752406788446, 936.765808547863, 949.7673070097732, 967.086103632075, 5835.0, 5868.530517558585, 5898.675572843449, 5913.113033045078, 6085.482733967662, 917.9678711245074, 928.4522131942186, 937.2970272295778, 962.5091203486272, 962.7496850818698, 3939.763614183101, 4044.2518134145275, 4088.0, 4164.437800896901, 4165.708400412002, 10873.565057397616, 11216.439178041066, 11248.662540123809, 11265.0, 11310.09519238459, 11042.779144417227, 11126.0, 11136.610218642309, 11142.721431964266, 11165.83251251232, 7876.606169691492, 8117.918820811717, 8241.587920604066, 8250.793730156794, 8258.25675689191, 5853.795117170878, 5987.970060149845, 6070.423309785563, 6123.173371594778, 6249.593776404519, 10268.127209187303, 10313.1233845124, 10315.638952636622, 10326.896731032593, 10407.271490995861, 9347.0, 9569.0, 9621.759456013655, 9897.901020989699, 9904.950475247577, 7720.884186737091, 7898.881516777138, 7916.08964969036, 7972.322352600094, 7972.920270797218, 8517.95741021291, 8526.147385261424, 8573.957130214309, 8651.567421629019, 8701.956490217508, 7732.536047837148, 7795.0, 7801.843963120819, 7819.178986206366, 8071.07182674, 7521.661525231269, 7528.4353673473615, 7641.7707468776025, 7680.385569154342, 7791.92208077912, 7211.963940180265, 7215.71137154508, 7259.003773962203, 7299.233580474186, 7317.15852676949, 4974.726390048582, 4978.253262010728, 5088.363954505687, 5092.821751238689, 5186.299849520266, 4965.677230979974, 5073.720945348041, 5182.844514664566, 5185.533302002837, 5235.0], "value": 4629.308258325912, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.8636599896044, 316.1968599756618, 322.3842460899682, 323.24522240568274, 324.063456610396, 5262.079136151177, 5384.311746453682, 5453.863653408666, 5463.535599474045, 5464.169503216427, 5251.212318152279, 5335.573154147669, 5399.082156033474, 5434.3207099112615, 5447.727613619323, 4235.369382787618, 4247.490301163864, 4260.659147268227, 4267.295896177127, 4274.914501709964, 4175.498940127189, 4183.853565125221, 4207.768572728499, 4284.164587905359, 4285.485741710999, 4175.707700460969, 4177.180403227824, 4184.246835569596, 4190.407645094875, 4191.853285135021, 4177.7284476509085, 4180.393842892779, 4214.808299684178, 4219.472565929259, 4221.767802770846, 3710.7216958728204, 3714.888553343404, 3730.1047748540154, 3730.5150330457136, 3732.2908647356917, 5057.317262169597, 5224.373075230958, 5266.368035835724, 5282.154855223184, 5294.470552944713, 4724.480307166209, 4756.573028091559, 4767.594754445876, 4770.7376094314795, 4803.711777293373, 5389.649672771257, 5623.578231632603, 5642.943570564283, 5645.463680950304, 5653.9151912721345, 3948.368261078214, 3959.742616729903, 3964.5242570891387, 3968.603139686037, 3973.3443981742976, 5849.883002339972, 5856.297244330665, 5860.882782344372, 5863.5016023638045, 5882.147088672119, 3999.0402303446963, 4065.6747460203114, 4089.8364065437645, 4091.324931386288, 4094.5905409458815, 4104.363823607346, 4108.0, 4112.712110152261, 4114.115465175002, 4127.772972486482, 4704.858854234379, 4728.345079222314, 4730.858074257778, 4735.0, 4737.502562230985, 5566.332040155207, 5651.519620832275, 5654.264945557051, 5666.603337766399, 5673.262475878192, 1414.8585141485976, 1416.6600015996294, 1423.4235134770433, 1432.6919712261913, 1436.5331267338156, 872.9869051964225, 883.8642344587174, 888.3515034025198, 896.87892134561, 904.9638014479347, 4792.808287668454, 4811.099615651798, 4815.422149342031, 4822.132016237111, 4867.756612169377, 5069.8592816615965, 5081.2632168335385, 5081.568066714371, 5114.897702045938, 5190.780166660832, 4819.927701084486, 4830.420349558076, 4838.419389673261, 4845.878853028632, 5149.6910185389, 3054.9083527493767, 3091.335362896988, 3094.6538255858773, 3097.3495565930957, 3104.674009229044, 5984.977586318968, 6023.6145686492355, 6023.69881505923, 6046.879062418727, 6049.727762250709, 5641.689707066151, 5927.318358388754, 5937.406259374028, 5979.820605381846, 5985.191999080069, 6000.33996260411, 6034.426729460694, 6041.78853740117, 6063.575549711478, 6095.51235901118, 5601.80393686219, 5871.295444546682, 5896.911546326723, 5915.467607915309, 5917.201177840936, 5721.370649228665, 5739.971300143473, 5748.48263656273, 5814.418558144152, 5848.853778655568, 6002.909956350571, 6012.128241405059, 6021.7591296347655, 6027.517798576016, 6030.547708921936, 3879.961200388073, 4038.7374820636564, 4055.209234199303, 4060.7766572838777, 4062.431259623596, 5771.971140144273, 5777.191193232867, 5799.159121927381, 5809.767609295581, 5817.0, 5544.61187716856, 5671.149327600893, 5700.230468886647, 5718.228039214653, 5743.253377060955, 5692.203091567267, 5696.772129114789, 5714.428557144252, 5722.5421966241765, 5745.741441635053, 5524.254225679642, 5528.0, 5573.804916827891, 5574.554035677055, 5619.438056194348, 5482.17767334903, 5663.752185860604, 5670.943290567042, 5713.885792270467, 5729.8567535811935, 5564.8330550083565, 5571.8885622288135, 5576.358718747396, 5598.0, 5604.159376093456], "value": 4253.613531248177, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.7376544443999, 768.8885111658809, 791.6516732637639, 795.904491461024, 825.7233826668067, 13401.1959282443, 13934.51229206978, 13979.510717124902, 14033.859661403396, 14219.146851188923, 12562.120651554394, 12666.493340266401, 12670.23978561287, 12671.113022088457, 12689.0, 641.1056576076371, 661.9172603424572, 776.9533827970312, 786.6460092958163, 796.6574373019599, 791.9168487308837, 795.4948607634165, 796.8725003999361, 800.8118092248317, 800.9799755006143, 848.5121055393142, 855.2815634866711, 855.2815634866711, 855.670566831768, 855.8801767752519, 683.3849535418113, 701.1726163127533, 818.3616778912458, 835.381817455085, 840.0633293877282, 609.5458883132051, 609.7530500147448, 610.2859654204591, 610.4201009041428, 610.6305685060519, 13166.934165329112, 13252.668683282898, 13300.667483312995, 13314.533991310354, 13350.332483375792, 12800.231986080866, 12974.610761677164, 13330.200187988654, 13536.120152190175, 13640.727185456335, 12536.373181340987, 12710.364481775874, 12913.225206487547, 13081.149725267922, 13265.403056862464, 4345.392204884213, 4390.429244198266, 4442.8667139985855, 4467.932981005288, 4487.259602165639, 61542.69228653873, 61796.99999999955, 62310.37689623091, 62511.37488625146, 64332.67833660802, 10683.57265709363, 10700.090492308242, 10811.513481893333, 11028.22802403824, 11095.334279943228, 17798.733019004725, 17875.37436189727, 17958.371456998942, 18634.34779782701, 19413.708794368096, 7468.290512401313, 7541.509801862861, 7924.4056695746895, 7956.880646790303, 8063.233992770677, 4339.175556644227, 4408.162449134654, 4487.147441986012, 4541.205289074399, 4667.019925815549, 3403.1662242750685, 3483.4426491761196, 3515.8593656253975, 3519.7536172468194, 3521.9471707924404, 558.4052983572483, 561.6545824318015, 565.7058329668549, 585.4175095779726, 604.1360853978765, 588.8086570786263, 695.0824911117277, 698.6960672107649, 709.4041005555358, 741.22541943669, 8981.640734370683, 9287.90712092884, 9555.474448905237, 9663.758406039906, 9683.515824208762, 602.6625089949642, 710.204570880617, 733.4865594084056, 755.6033082631662, 773.4044785515122, 4470.374147619336, 4726.787294571753, 4811.879703007383, 4828.927566086511, 4841.225403935354, 54179.45820541744, 61944.0, 75258.24741752622, 83332.2501137384, 88721.11278887128, 46404.76797615991, 68214.3178568208, 77509.61245193738, 77741.61129194431, 78831.60584197042, 66707.0, 69722.65138674274, 70538.2946170542, 70654.2934570658, 71676.283237168, 9414.0, 9816.705498835046, 9825.508724563886, 9916.851247231152, 9979.505123719071, 40725.981850452794, 40926.59073409228, 41723.7913810429, 41988.0, 42643.147137057706, 57101.71449142728, 57897.421025790885, 59350.0, 59566.40433595782, 60749.39250607437, 12055.81916271239, 12169.148159628741, 12373.876261237632, 12381.75236495283, 12524.812127817908, 12891.0, 13012.8048079277, 13095.0, 13100.737985240052, 13292.069555131047, 12173.69565760863, 12261.141720079866, 12377.938110309751, 12732.172408793398, 13256.53602123921, 13296.800547991594, 13451.529196478075, 13503.72992540124, 13600.183988960694, 13736.65658358509, 12627.305498197687, 12629.747405051663, 12758.234505929673, 13338.13302135358, 13380.799288010492, 10145.28982971185, 10258.538365773707, 10398.0, 10487.423191724529, 11506.252093613888, 10524.473776311, 11128.332300062022, 11324.207305488537, 11341.489632966372, 11435.313881167156], "value": 6967.120225751838, "unit": "score"}], "name": "dromaeo_css", "value": 7064.573719818797, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 110.0, 110.0, 111.0, 111.0, 110.0, 109.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 123.0, 122.0, 158.0, 123.0, 125.0, 122.0, 122.0, 160.0, 125.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 160.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 111.0, 75.0, 76.0, 77.0, 75.0, 99.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 122.0, 89.0, 81.0, 82.0, 105.0, 81.0, 82.0, 109.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 147.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 85.0, 85.0, 85.0, 84.0, 84.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 59.0, 57.0, 55.0, 56.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 81.0, 79.0, 84.0, 79.0, 87.0, 87.0, 79.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 136.0, 140.0, 131.0, 129.0, 132.0, 134.0, 133.0, 177.0, 135.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 167.0, 172.0, 165.0, 165.0, 171.0, 178.0, 166.0, 168.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 66.0, 65.0, 64.0, 65.0, 68.0, 65.0, 65.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1493.4, "alertThreshold": 2.0}]} 22:48:23 INFO - Return code: 0 22:48:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:48:23 INFO - # TBPL SUCCESS # 22:48:23 INFO - Running post-action listener: _resource_record_post_action 22:48:23 INFO - [mozharness: 2016-10-27 05:48:23.799459Z] Finished run-tests step (success) 22:48:23 INFO - Running post-run listener: _resource_record_post_run 22:48:23 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 20480; Write bytes: 391593984; Read time: 200; Write time: 277436 22:48:23 INFO - TinderboxPrint: CPU usage
13.0% 22:48:23 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 200 22:48:23 INFO - TinderboxPrint: I/O write bytes / time
391,593,984 / 277,436 22:48:23 INFO - TinderboxPrint: CPU idle
8,297.2 (86.9%) 22:48:23 INFO - TinderboxPrint: CPU user
1,196.0 (12.5%) 22:48:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:48:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 103559168; Read time: 0; Write time: 80716 22:48:23 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 20480; Write bytes: 288034816; Read time: 200; Write time: 196720 22:48:24 INFO - Running post-run listener: _upload_blobber_files 22:48:24 INFO - Blob upload gear active. 22:48:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:48: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:48: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:48: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:48:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:48:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:48:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:48:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:48:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:28 INFO - (blobuploader) - INFO - Done attempting. 22:48:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:48:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:48:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:48:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:28 INFO - (blobuploader) - INFO - Done attempting. 22:48:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:48:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:48:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:48:29 INFO - (blobuploader) - INFO - Done attempting. 22:48:29 INFO - (blobuploader) - INFO - Iteration through files over. 22:48:29 INFO - Return code: 0 22:48:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:48:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:48:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbad2b29f175327fde33c81d54ac4b6e0b1341f100c8684e42e87a813d05712391ed74439160c231e6af73bc63e92402746524dc951969b9f3fb3b5a07fc30da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9baf8887646c2fdb4f66567422241d5dff91f9f83e13716333c0633b3e735856fae7166e4d23c865b944e4dac5b8d9377fc8b5415d34ec5ac944aecc1fa8cdec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92794f1a1b95401772680f7f2e4f0dfdcca4605662272d380664f7b29ef2d29538d9d86e132eeb2e0a761a70d1c97722303aa9e77fc9fe3dac21c56fe0336791"} 22:48:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:48:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:48:29 INFO - Contents: 22:48:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbad2b29f175327fde33c81d54ac4b6e0b1341f100c8684e42e87a813d05712391ed74439160c231e6af73bc63e92402746524dc951969b9f3fb3b5a07fc30da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9baf8887646c2fdb4f66567422241d5dff91f9f83e13716333c0633b3e735856fae7166e4d23c865b944e4dac5b8d9377fc8b5415d34ec5ac944aecc1fa8cdec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92794f1a1b95401772680f7f2e4f0dfdcca4605662272d380664f7b29ef2d29538d9d86e132eeb2e0a761a70d1c97722303aa9e77fc9fe3dac21c56fe0336791"} 22:48:29 INFO - Running post-run listener: copy_logs_to_upload_dir 22:48:29 INFO - Copying logs to upload dir... 22:48:29 INFO - mkdir: /builds/slave/test/build/upload/logs 22:48:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.445354 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-26 22:48:29.292066) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:48:29.296182) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 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/bbad2b29f175327fde33c81d54ac4b6e0b1341f100c8684e42e87a813d05712391ed74439160c231e6af73bc63e92402746524dc951969b9f3fb3b5a07fc30da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9baf8887646c2fdb4f66567422241d5dff91f9f83e13716333c0633b3e735856fae7166e4d23c865b944e4dac5b8d9377fc8b5415d34ec5ac944aecc1fa8cdec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92794f1a1b95401772680f7f2e4f0dfdcca4605662272d380664f7b29ef2d29538d9d86e132eeb2e0a761a70d1c97722303aa9e77fc9fe3dac21c56fe0336791"} build_url:https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/OIzaWeFRTMmN7NqRXmUQGw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbad2b29f175327fde33c81d54ac4b6e0b1341f100c8684e42e87a813d05712391ed74439160c231e6af73bc63e92402746524dc951969b9f3fb3b5a07fc30da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9baf8887646c2fdb4f66567422241d5dff91f9f83e13716333c0633b3e735856fae7166e4d23c865b944e4dac5b8d9377fc8b5415d34ec5ac944aecc1fa8cdec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92794f1a1b95401772680f7f2e4f0dfdcca4605662272d380664f7b29ef2d29538d9d86e132eeb2e0a761a70d1c97722303aa9e77fc9fe3dac21c56fe0336791"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:48:29.323026) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:48:29.323544) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477546068.646196-877148817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004404 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:48:29.407771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:48:29.408165) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:48:29.408577) ========= ========= Total master_lag: 0.18 =========