builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1478142569.97 results: success (0) buildid: 20161102113519 builduid: 4c906b0f81a147a49e3eb732ebc6503c revision: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:29.968000) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:29.968494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:29.968771) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:29.983092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:29.983462) ========= 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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-02 20:09:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2016-11-02 20:09:30 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135512 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:30.132918) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:30.133250) ========= 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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017066 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:30.182896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:30.183253) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 --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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-02 20:09:30,668 truncating revision to first 12 chars 2016-11-02 20:09:30,668 Setting DEBUG logging. 2016-11-02 20:09:30,668 attempt 1/10 2016-11-02 20:09:30,668 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ec5ec8b7f05c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-02 20:09:31,230 unpacking tar archive at: mozilla-inbound-ec5ec8b7f05c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.903884 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:31.101534) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:31.101877) ========= script_repo_revision: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:31.102269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:31.102535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-02 20:09:31.111805) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-11-02 20:09:31.112083) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:09:31 INFO - MultiFileLogger online at 20161102 20:09:31 in /builds/slave/test 20:09:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 20:09:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:09:31 INFO - {'append_to_log': False, 20:09:31 INFO - 'base_work_dir': '/builds/slave/test', 20:09:31 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 20:09:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:09:31 INFO - 'branch': 'Mozilla-Inbound', 20:09:31 INFO - 'buildbot_json_path': 'buildprops.json', 20:09:31 INFO - 'config_files': ('talos/linux_config.py',), 20:09:31 INFO - 'default_actions': ('clobber', 20:09:31 INFO - 'read-buildbot-config', 20:09:31 INFO - 'download-and-extract', 20:09:31 INFO - 'populate-webroot', 20:09:31 INFO - 'create-virtualenv', 20:09:31 INFO - 'install', 20:09:31 INFO - 'run-tests'), 20:09:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:09:31 INFO - 'download_minidump_stackwalk': True, 20:09:31 INFO - 'download_symbols': 'ondemand', 20:09:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:09:31 INFO - 'tooltool.py': '/tools/tooltool.py', 20:09:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:09:31 INFO - '/tools/misc-python/virtualenv.py')}, 20:09:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:09:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:09:31 INFO - 'installer_path': 'installer.exe', 20:09:31 INFO - 'log_level': 'info', 20:09:31 INFO - 'log_name': 'talos', 20:09:31 INFO - 'log_to_console': True, 20:09:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:09:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:09:31 INFO - 'opt_config_files': (), 20:09:31 INFO - 'pip_index': False, 20:09:31 INFO - 'sps_profile': False, 20:09:31 INFO - 'sps_profile_interval': 0, 20:09:31 INFO - 'suite': 'dromaeojs', 20:09:31 INFO - 'system_bits': '32', 20:09:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:09:31 INFO - 'title': 'talos-linux64-ix-053', 20:09:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:09:31 INFO - 'use_talos_json': True, 20:09:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:09:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:09:31 INFO - 'work_dir': 'build'} 20:09:31 INFO - [mozharness: 2016-11-03 03:09:31.702474Z] Running clobber step. 20:09:31 INFO - Running pre-action listener: _resource_record_pre_action 20:09:31 INFO - Running main action method: clobber 20:09:31 INFO - rmtree: /builds/slave/test/build 20:09:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:09:32 INFO - Running post-action listener: _resource_record_post_action 20:09:32 INFO - [mozharness: 2016-11-03 03:09:32.678330Z] Finished clobber step (success) 20:09:32 INFO - [mozharness: 2016-11-03 03:09:32.678429Z] Running read-buildbot-config step. 20:09:32 INFO - Running pre-action listener: _resource_record_pre_action 20:09:32 INFO - Running main action method: read_buildbot_config 20:09:32 INFO - Using buildbot properties: 20:09:32 INFO - { 20:09:32 INFO - "project": "", 20:09:32 INFO - "product": "firefox", 20:09:32 INFO - "script_repo_revision": "production", 20:09:32 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 20:09:32 INFO - "repository": "", 20:09:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 20:09:32 INFO - "buildid": "20161102113519", 20:09:32 INFO - "stage_platform": "linux64-pgo", 20:09:32 INFO - "basedir": "/builds/slave/test", 20:09:32 INFO - "buildnumber": 1970, 20:09:32 INFO - "platform": "ubuntu64_hw", 20:09:32 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:09:32 INFO - "slavebuilddir": "test", 20:09:32 INFO - "branch": "mozilla-inbound", 20:09:32 INFO - "revision": "ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3", 20:09:32 INFO - "slavename": "talos-linux64-ix-053", 20:09:32 INFO - "builduid": "4c906b0f81a147a49e3eb732ebc6503c", 20:09:32 INFO - "repo_path": "integration/mozilla-inbound" 20:09:32 INFO - } 20:09:32 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 20:09:32 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 20:09:32 INFO - Running post-action listener: _resource_record_post_action 20:09:32 INFO - [mozharness: 2016-11-03 03:09:32.680801Z] Finished read-buildbot-config step (success) 20:09:32 INFO - [mozharness: 2016-11-03 03:09:32.680890Z] Running download-and-extract step. 20:09:32 INFO - Running pre-action listener: _resource_record_pre_action 20:09:32 INFO - Running main action method: download_and_extract 20:09:32 INFO - mkdir: /builds/slave/test/build/tests 20:09:32 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')]} 20:09:32 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 20:09:32 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/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 20:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/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 20:09:32 INFO - Downloaded 1361 bytes. 20:09:32 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 20:09:32 INFO - Using the following test package requirements: 20:09:32 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 20:09:32 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:09:32 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:09:32 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'jsshell-linux-x86_64.zip'], 20:09:32 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:09:32 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 20:09:32 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:09:32 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:09:32 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:09:32 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 20:09:32 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:09:32 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:09:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 20:09:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:09:32 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:09:32 INFO - Http code: 200 20:09:32 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 20:09:32 INFO - x-amz-cf-id: Ryoo3QZmEVgZXUmWQBAwQD7iJbDwFlS6m16kpyzpvE-AUd2ffzy_Nw== 20:09:32 INFO - x-amz-replication-status: COMPLETED 20:09:32 INFO - x-amz-version-id: 0yoSN_KdLSg5wP1rBdajxLyYM38.JA4O 20:09:32 INFO - Expected file size: 22023921 20:09:32 INFO - Obtained file size: 22023921 20:09:37 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 20:09:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 20:09:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:09:37 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 20:09:37 INFO - Http code: 200 20:09:37 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 20:09:37 INFO - x-amz-cf-id: uL80qWcwYi6VE4uzHcEglNe30NUFsDIN9SHq97Vh90Zdp6wqTm6_GA== 20:09:37 INFO - x-amz-replication-status: COMPLETED 20:09:37 INFO - x-amz-version-id: 0yoSN_KdLSg5wP1rBdajxLyYM38.JA4O 20:09:37 INFO - Expected file size: 22023921 20:09:37 INFO - Obtained file size: 22023921 20:09:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 20:09:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:09:41 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 20:09:41 INFO - Http code: 200 20:09:41 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 20:09:41 INFO - x-amz-cf-id: OhDbnfgVR7XxuZel4CAeyoHbi2gdQ5qyZ5JeLNJ2Ykhnn6yQi5d6CQ== 20:09:41 INFO - x-amz-replication-status: COMPLETED 20:09:41 INFO - x-amz-version-id: jGQBtSSr5NhE.UrZBVy8KUhD3mreaGIo 20:09:42 INFO - Expected file size: 14409038 20:09:42 INFO - Obtained file size: 14409038 20:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:42 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:09:42 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/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 20:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/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 20:09:43 INFO - Downloaded 59615918 bytes. 20:09:43 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:09:43 INFO - mkdir: /builds/slave/test/properties 20:09:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:09:43 INFO - Writing to file /builds/slave/test/properties/build_url 20:09:43 INFO - Contents: 20:09:43 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 20:09:43 INFO - Running post-action listener: _resource_record_post_action 20:09:43 INFO - Running post-action listener: set_extra_try_arguments 20:09:43 INFO - [mozharness: 2016-11-03 03:09:43.399946Z] Finished download-and-extract step (success) 20:09:43 INFO - [mozharness: 2016-11-03 03:09:43.400175Z] Running populate-webroot step. 20:09:43 INFO - Running pre-action listener: _resource_record_pre_action 20:09:43 INFO - Running main action method: populate_webroot 20:09:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:09:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:09:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:09:43 INFO - '--tppagecycles', 20:09:43 INFO - '7'], 20:09:43 INFO - 'tests': ['tsvgm']}}, 20:09:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:09:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:09:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:09:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:09:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['damp', 'tps']}, 20:09:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'tests': ['damp', 'tps']}, 20:09:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['dromaeo_dom']}, 20:09:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:09:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['basic_compositor_video']}, 20:09:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:09:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'other-e10s': {'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['a11yr', 20:09:43 INFO - 'ts_paint', 20:09:43 INFO - 'tpaint', 20:09:43 INFO - 'sessionrestore', 20:09:43 INFO - 'sessionrestore_no_auto_restore', 20:09:43 INFO - 'tabpaint']}, 20:09:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['tsvgx', 20:09:43 INFO - 'tsvgr_opacity', 20:09:43 INFO - 'tart', 20:09:43 INFO - 'tscrollx', 20:09:43 INFO - 'cart']}, 20:09:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:09:43 INFO - 'tsvgr_opacity', 20:09:43 INFO - 'tart', 20:09:43 INFO - 'tscrollx', 20:09:43 INFO - 'cart']}, 20:09:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'talos_options': ['--disable-e10s'], 20:09:43 INFO - 'tests': ['tp5o']}, 20:09:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'tests': ['tp5o']}, 20:09:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'talos_options': ['--disable-e10s', 20:09:43 INFO - '--xperf_path', 20:09:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:43 INFO - 'tests': ['tp5n']}, 20:09:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:43 INFO - 'talos_options': ['--xperf_path', 20:09:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:43 INFO - 'tests': ['tp5n']}}, 20:09:43 INFO - 'talos.zip': {'path': '', 20:09:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:09:43 INFO - Running post-action listener: _resource_record_post_action 20:09:43 INFO - [mozharness: 2016-11-03 03:09:43.420519Z] Finished populate-webroot step (success) 20:09:43 INFO - [mozharness: 2016-11-03 03:09:43.420607Z] Running create-virtualenv step. 20:09:43 INFO - Running pre-action listener: _resource_record_pre_action 20:09:43 INFO - Running main action method: create_virtualenv 20:09:43 INFO - Creating virtualenv /builds/slave/test/build/venv 20:09:43 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 20:09:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:09:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:09:43 INFO - Using real prefix '/usr' 20:09:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:09:44 INFO - Installing distribute.............................................................................................................................................................................................done. 20:09:45 INFO - Installing pip.................done. 20:09:45 INFO - Return code: 0 20:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:09:46 INFO - Reading from file tmpfile_stdout 20:09:46 INFO - Output received: 20:09:46 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) 20:09:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:09:46 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')]} 20:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:46 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')]} 20:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:46 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:46 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 20:09:46 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 20:09:46 INFO - Using env: {'DISPLAY': ':0', 20:09:46 INFO - 'HOME': '/home/cltbld', 20:09:46 INFO - 'LANG': 'en_US.UTF-8', 20:09:46 INFO - 'LANGUAGE': 'en_US:en', 20:09:46 INFO - 'LOGNAME': 'cltbld', 20:09:46 INFO - 'MAIL': '/var/mail/cltbld', 20:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:46 INFO - 'MOZ_NO_REMOTE': '1', 20:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:46 INFO - 'NO_EM_RESTART': '1', 20:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:46 INFO - 'PWD': '/builds/slave/test', 20:09:46 INFO - 'SHELL': '/bin/bash', 20:09:46 INFO - 'SHLVL': '1', 20:09:46 INFO - 'TERM': 'linux', 20:09:46 INFO - 'TMOUT': '86400', 20:09:46 INFO - 'USER': 'cltbld', 20:09:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:46 INFO - '_': '/tools/buildbot/bin/python'} 20:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:46 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 20:09:46 INFO - Cleaning up... 20:09:46 INFO - Return code: 0 20:09:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:09:46 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')]} 20:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:46 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')]} 20:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:46 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:46 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 20:09:46 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 20:09:46 INFO - Using env: {'DISPLAY': ':0', 20:09:46 INFO - 'HOME': '/home/cltbld', 20:09:46 INFO - 'LANG': 'en_US.UTF-8', 20:09:46 INFO - 'LANGUAGE': 'en_US:en', 20:09:46 INFO - 'LOGNAME': 'cltbld', 20:09:46 INFO - 'MAIL': '/var/mail/cltbld', 20:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:46 INFO - 'MOZ_NO_REMOTE': '1', 20:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:46 INFO - 'NO_EM_RESTART': '1', 20:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:46 INFO - 'PWD': '/builds/slave/test', 20:09:46 INFO - 'SHELL': '/bin/bash', 20:09:46 INFO - 'SHLVL': '1', 20:09:46 INFO - 'TERM': 'linux', 20:09:46 INFO - 'TMOUT': '86400', 20:09:46 INFO - 'USER': 'cltbld', 20:09:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:46 INFO - '_': '/tools/buildbot/bin/python'} 20:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:46 INFO - Downloading/unpacking psutil>=3.1.1 20:09:46 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 20:09:46 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 20:09:46 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 20:09:46 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 20:09:46 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 20:09:46 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 20:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:09:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:48 INFO - Installing collected packages: psutil 20:09:48 INFO - Running setup.py install for psutil 20:09:48 INFO - building 'psutil._psutil_linux' extension 20:09:48 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 20:09:48 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 20:09:48 INFO - building 'psutil._psutil_posix' extension 20:09:48 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 20:09:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:09:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:49 INFO - Successfully installed psutil 20:09:49 INFO - Cleaning up... 20:09:49 INFO - Return code: 0 20:09:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:49 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:49 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 20:09:49 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 20:09:49 INFO - Using env: {'DISPLAY': ':0', 20:09:49 INFO - 'HOME': '/home/cltbld', 20:09:49 INFO - 'LANG': 'en_US.UTF-8', 20:09:49 INFO - 'LANGUAGE': 'en_US:en', 20:09:49 INFO - 'LOGNAME': 'cltbld', 20:09:49 INFO - 'MAIL': '/var/mail/cltbld', 20:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:49 INFO - 'MOZ_NO_REMOTE': '1', 20:09:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:49 INFO - 'NO_EM_RESTART': '1', 20:09:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:49 INFO - 'PWD': '/builds/slave/test', 20:09:49 INFO - 'SHELL': '/bin/bash', 20:09:49 INFO - 'SHLVL': '1', 20:09:49 INFO - 'TERM': 'linux', 20:09:49 INFO - 'TMOUT': '86400', 20:09:49 INFO - 'USER': 'cltbld', 20:09:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:49 INFO - '_': '/tools/buildbot/bin/python'} 20:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:09:49 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 20:09:49 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 20:09:49 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 20:09:49 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 20:09:49 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 20:09:49 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 20:09:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:09:51 INFO - Installing collected packages: mozsystemmonitor 20:09:51 INFO - Running setup.py install for mozsystemmonitor 20:09:51 INFO - Successfully installed mozsystemmonitor 20:09:51 INFO - Cleaning up... 20:09:51 INFO - Return code: 0 20:09:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:51 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:51 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 20:09:51 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 20:09:51 INFO - Using env: {'DISPLAY': ':0', 20:09:51 INFO - 'HOME': '/home/cltbld', 20:09:51 INFO - 'LANG': 'en_US.UTF-8', 20:09:51 INFO - 'LANGUAGE': 'en_US:en', 20:09:51 INFO - 'LOGNAME': 'cltbld', 20:09:51 INFO - 'MAIL': '/var/mail/cltbld', 20:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:51 INFO - 'MOZ_NO_REMOTE': '1', 20:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:51 INFO - 'NO_EM_RESTART': '1', 20:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:51 INFO - 'PWD': '/builds/slave/test', 20:09:51 INFO - 'SHELL': '/bin/bash', 20:09:51 INFO - 'SHLVL': '1', 20:09:51 INFO - 'TERM': 'linux', 20:09:51 INFO - 'TMOUT': '86400', 20:09:51 INFO - 'USER': 'cltbld', 20:09:51 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:51 INFO - '_': '/tools/buildbot/bin/python'} 20:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:51 INFO - Downloading/unpacking jsonschema==2.5.1 20:09:51 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 20:09:51 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 20:09:51 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 20:09:51 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 20:09:51 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 20:09:51 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 20:09:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:09:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:09:53 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 20:09:53 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 20:09:53 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 20:09:53 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 20:09:53 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 20:09:53 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 20:09:53 INFO - Downloading functools32-3.2.3-2.tar.gz 20:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:09:53 INFO - warning: no files found matching '*.txt' 20:09:53 INFO - no previously-included directories found matching 'build' 20:09:53 INFO - no previously-included directories found matching 'dist' 20:09:53 INFO - no previously-included directories found matching '.git*' 20:09:53 INFO - Installing collected packages: jsonschema, functools32 20:09:54 INFO - Running setup.py install for functools32 20:09:54 INFO - warning: no files found matching '*.txt' 20:09:54 INFO - no previously-included directories found matching 'build' 20:09:54 INFO - no previously-included directories found matching 'dist' 20:09:54 INFO - no previously-included directories found matching '.git*' 20:09:54 INFO - Successfully installed jsonschema functools32 20:09:54 INFO - Cleaning up... 20:09:54 INFO - Return code: 0 20:09:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:09:54 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')]} 20:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:54 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')]} 20:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:54 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:54 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 20:09:54 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 20:09:54 INFO - Using env: {'DISPLAY': ':0', 20:09:54 INFO - 'HOME': '/home/cltbld', 20:09:54 INFO - 'LANG': 'en_US.UTF-8', 20:09:54 INFO - 'LANGUAGE': 'en_US:en', 20:09:54 INFO - 'LOGNAME': 'cltbld', 20:09:54 INFO - 'MAIL': '/var/mail/cltbld', 20:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:54 INFO - 'MOZ_NO_REMOTE': '1', 20:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:54 INFO - 'NO_EM_RESTART': '1', 20:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:54 INFO - 'PWD': '/builds/slave/test', 20:09:54 INFO - 'SHELL': '/bin/bash', 20:09:54 INFO - 'SHLVL': '1', 20:09:54 INFO - 'TERM': 'linux', 20:09:54 INFO - 'TMOUT': '86400', 20:09:54 INFO - 'USER': 'cltbld', 20:09:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:54 INFO - '_': '/tools/buildbot/bin/python'} 20:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:09:54 INFO - Cleaning up... 20:09:54 INFO - Return code: 0 20:09:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:09:54 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')]} 20:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:54 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')]} 20:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:54 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:54 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 20:09:54 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 20:09:54 INFO - Using env: {'DISPLAY': ':0', 20:09:54 INFO - 'HOME': '/home/cltbld', 20:09:54 INFO - 'LANG': 'en_US.UTF-8', 20:09:54 INFO - 'LANGUAGE': 'en_US:en', 20:09:54 INFO - 'LOGNAME': 'cltbld', 20:09:54 INFO - 'MAIL': '/var/mail/cltbld', 20:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:54 INFO - 'MOZ_NO_REMOTE': '1', 20:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:54 INFO - 'NO_EM_RESTART': '1', 20:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:54 INFO - 'PWD': '/builds/slave/test', 20:09:54 INFO - 'SHELL': '/bin/bash', 20:09:54 INFO - 'SHLVL': '1', 20:09:54 INFO - 'TERM': 'linux', 20:09:54 INFO - 'TMOUT': '86400', 20:09:54 INFO - 'USER': 'cltbld', 20:09:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:54 INFO - '_': '/tools/buildbot/bin/python'} 20:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:54 INFO - Downloading/unpacking blobuploader==1.2.4 20:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:56 INFO - Downloading blobuploader-1.2.4.tar.gz 20:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:09:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:09:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:57 INFO - Downloading docopt-0.6.1.tar.gz 20:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:09:57 INFO - Installing collected packages: blobuploader, requests, docopt 20:09:57 INFO - Running setup.py install for blobuploader 20:09:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:09:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:09:57 INFO - Running setup.py install for requests 20:09:57 INFO - Running setup.py install for docopt 20:09:57 INFO - Successfully installed blobuploader requests docopt 20:09:57 INFO - Cleaning up... 20:09:58 INFO - Return code: 0 20:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:09:58 INFO - Using env: {'DISPLAY': ':0', 20:09:58 INFO - 'HOME': '/home/cltbld', 20:09:58 INFO - 'LANG': 'en_US.UTF-8', 20:09:58 INFO - 'LANGUAGE': 'en_US:en', 20:09:58 INFO - 'LOGNAME': 'cltbld', 20:09:58 INFO - 'MAIL': '/var/mail/cltbld', 20:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:58 INFO - 'MOZ_NO_REMOTE': '1', 20:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:09:58 INFO - 'NO_EM_RESTART': '1', 20:09:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:58 INFO - 'PWD': '/builds/slave/test', 20:09:58 INFO - 'SHELL': '/bin/bash', 20:09:58 INFO - 'SHLVL': '1', 20:09:58 INFO - 'TERM': 'linux', 20:09:58 INFO - 'TMOUT': '86400', 20:09:58 INFO - 'USER': 'cltbld', 20:09:58 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:58 INFO - '_': '/tools/buildbot/bin/python'} 20:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:58 INFO - Running setup.py (path:/tmp/pip-OR8bO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:58 INFO - Running setup.py (path:/tmp/pip-b7iP1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:58 INFO - Running setup.py (path:/tmp/pip-siCF60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:58 INFO - Running setup.py (path:/tmp/pip-aNG_Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:58 INFO - Running setup.py (path:/tmp/pip-4GLPJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:58 INFO - Running setup.py (path:/tmp/pip-LFvoAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:58 INFO - Running setup.py (path:/tmp/pip-4jofol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:58 INFO - Running setup.py (path:/tmp/pip-2XKISv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:58 INFO - Running setup.py (path:/tmp/pip-Gnm08h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:58 INFO - Running setup.py (path:/tmp/pip-85Vx9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:58 INFO - Running setup.py (path:/tmp/pip-mZLVR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:59 INFO - Running setup.py (path:/tmp/pip-FK3IEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:59 INFO - Running setup.py (path:/tmp/pip-6426rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:59 INFO - Running setup.py (path:/tmp/pip-jCvS9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:59 INFO - Running setup.py (path:/tmp/pip-RK5tdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:59 INFO - Running setup.py (path:/tmp/pip-zOc2s8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:59 INFO - Running setup.py (path:/tmp/pip-U71CXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:09:59 INFO - Running setup.py install for manifestparser 20:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozcrash 20:09:59 INFO - Running setup.py install for mozdebug 20:09:59 INFO - Running setup.py install for mozdevice 20:09:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozfile 20:09:59 INFO - Running setup.py install for mozhttpd 20:10:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozinfo 20:10:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozInstall 20:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozleak 20:10:00 INFO - Running setup.py install for mozlog 20:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for moznetwork 20:10:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozprocess 20:10:00 INFO - Running setup.py install for mozprofile 20:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozrunner 20:10:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozscreenshot 20:10:01 INFO - Running setup.py install for moztest 20:10:01 INFO - Running setup.py install for mozversion 20:10:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:10:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:10:01 INFO - Cleaning up... 20:10:01 INFO - Return code: 0 20:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:01 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:10:01 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 20:10:01 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 20:10:01 INFO - Using env: {'DISPLAY': ':0', 20:10:01 INFO - 'HOME': '/home/cltbld', 20:10:01 INFO - 'LANG': 'en_US.UTF-8', 20:10:01 INFO - 'LANGUAGE': 'en_US:en', 20:10:01 INFO - 'LOGNAME': 'cltbld', 20:10:01 INFO - 'MAIL': '/var/mail/cltbld', 20:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:01 INFO - 'MOZ_NO_REMOTE': '1', 20:10:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:01 INFO - 'NO_EM_RESTART': '1', 20:10:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:01 INFO - 'PWD': '/builds/slave/test', 20:10:01 INFO - 'SHELL': '/bin/bash', 20:10:01 INFO - 'SHLVL': '1', 20:10:01 INFO - 'TERM': 'linux', 20:10:01 INFO - 'TMOUT': '86400', 20:10:01 INFO - 'USER': 'cltbld', 20:10:01 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:01 INFO - '_': '/tools/buildbot/bin/python'} 20:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:01 INFO - Running setup.py (path:/tmp/pip-ESYTuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:01 INFO - Running setup.py (path:/tmp/pip-707OKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:01 INFO - Running setup.py (path:/tmp/pip-jy_6rX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:01 INFO - Running setup.py (path:/tmp/pip-53KuIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:01 INFO - Running setup.py (path:/tmp/pip-G3McKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:01 INFO - Running setup.py (path:/tmp/pip-2Mj06S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:01 INFO - Running setup.py (path:/tmp/pip-IlpX41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:01 INFO - Running setup.py (path:/tmp/pip-UxZNFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:02 INFO - Running setup.py (path:/tmp/pip-twbzPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:02 INFO - Running setup.py (path:/tmp/pip-ebbTuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:02 INFO - Running setup.py (path:/tmp/pip-CgLwKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:02 INFO - Running setup.py (path:/tmp/pip-q8rfex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:02 INFO - Running setup.py (path:/tmp/pip-l6msQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:02 INFO - Running setup.py (path:/tmp/pip-zzvNJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:02 INFO - Running setup.py (path:/tmp/pip-bIfmBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:02 INFO - Running setup.py (path:/tmp/pip-Gm2xEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:02 INFO - Running setup.py (path:/tmp/pip-ovLq8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:10:02 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 20:10:02 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 20:10:02 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 20:10:02 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 20:10:02 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 20:10:02 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 20:10:04 INFO - Downloading blessings-1.6.tar.gz 20:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:10:04 INFO - Installing collected packages: blessings 20:10:04 INFO - Running setup.py install for blessings 20:10:04 INFO - Successfully installed blessings 20:10:04 INFO - Cleaning up... 20:10:04 INFO - Return code: 0 20:10:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:05 INFO - Reading from file tmpfile_stdout 20:10:05 INFO - Current package versions: 20:10:05 INFO - argparse == 1.2.1 20:10:05 INFO - blessings == 1.6 20:10:05 INFO - blobuploader == 1.2.4 20:10:05 INFO - docopt == 0.6.1 20:10:05 INFO - functools32 == 3.2.3-2 20:10:05 INFO - jsonschema == 2.5.1 20:10:05 INFO - manifestparser == 1.1 20:10:05 INFO - mozInstall == 1.12 20:10:05 INFO - mozcrash == 0.17 20:10:05 INFO - mozdebug == 0.1 20:10:05 INFO - mozdevice == 0.48 20:10:05 INFO - mozfile == 1.2 20:10:05 INFO - mozhttpd == 0.7 20:10:05 INFO - mozinfo == 0.9 20:10:05 INFO - mozleak == 0.1 20:10:05 INFO - mozlog == 3.4 20:10:05 INFO - moznetwork == 0.27 20:10:05 INFO - mozprocess == 0.23 20:10:05 INFO - mozprofile == 0.28 20:10:05 INFO - mozrunner == 6.12 20:10:05 INFO - mozscreenshot == 0.1 20:10:05 INFO - mozsystemmonitor == 0.3 20:10:05 INFO - moztest == 0.7 20:10:05 INFO - mozversion == 1.4 20:10:05 INFO - psutil == 3.1.1 20:10:05 INFO - requests == 1.2.3 20:10:05 INFO - wsgiref == 0.1.2 20:10:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:10:05 INFO - Using env: {'DISPLAY': ':0', 20:10:05 INFO - 'HOME': '/home/cltbld', 20:10:05 INFO - 'LANG': 'en_US.UTF-8', 20:10:05 INFO - 'LANGUAGE': 'en_US:en', 20:10:05 INFO - 'LOGNAME': 'cltbld', 20:10:05 INFO - 'MAIL': '/var/mail/cltbld', 20:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:05 INFO - 'MOZ_NO_REMOTE': '1', 20:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:05 INFO - 'NO_EM_RESTART': '1', 20:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:05 INFO - 'PWD': '/builds/slave/test', 20:10:05 INFO - 'SHELL': '/bin/bash', 20:10:05 INFO - 'SHLVL': '1', 20:10:05 INFO - 'TERM': 'linux', 20:10:05 INFO - 'TMOUT': '86400', 20:10:05 INFO - 'USER': 'cltbld', 20:10:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:05 INFO - '_': '/tools/buildbot/bin/python'} 20:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 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)) 20:10:05 INFO - Cleaning up... 20:10:05 INFO - Return code: 0 20:10:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:05 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 0x7f07106b5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ba08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c6dca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, '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': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', '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 20:10:05 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 20:10:05 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 20:10:05 INFO - Using env: {'DISPLAY': ':0', 20:10:05 INFO - 'HOME': '/home/cltbld', 20:10:05 INFO - 'LANG': 'en_US.UTF-8', 20:10:05 INFO - 'LANGUAGE': 'en_US:en', 20:10:05 INFO - 'LOGNAME': 'cltbld', 20:10:05 INFO - 'MAIL': '/var/mail/cltbld', 20:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:05 INFO - 'MOZ_NO_REMOTE': '1', 20:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:05 INFO - 'NO_EM_RESTART': '1', 20:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:05 INFO - 'PWD': '/builds/slave/test', 20:10:05 INFO - 'SHELL': '/bin/bash', 20:10:05 INFO - 'SHLVL': '1', 20:10:05 INFO - 'TERM': 'linux', 20:10:05 INFO - 'TMOUT': '86400', 20:10:05 INFO - 'USER': 'cltbld', 20:10:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:05 INFO - '_': '/tools/buildbot/bin/python'} 20:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:10:05 INFO - Cleaning up... 20:10:05 INFO - Return code: 0 20:10:05 INFO - Running post-action listener: _resource_record_post_action 20:10:05 INFO - Running post-action listener: _start_resource_monitoring 20:10:05 INFO - Starting resource monitoring. 20:10:05 INFO - [mozharness: 2016-11-03 03:10:05.593056Z] Finished create-virtualenv step (success) 20:10:05 INFO - [mozharness: 2016-11-03 03:10:05.593527Z] Running install step. 20:10:05 INFO - Running pre-action listener: _resource_record_pre_action 20:10:05 INFO - Running main action method: install 20:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:05 INFO - Reading from file tmpfile_stdout 20:10:05 INFO - Detecting whether we're running mozinstall >=1.0... 20:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:10:05 INFO - Reading from file tmpfile_stdout 20:10:05 INFO - Output received: 20:10:05 INFO - Usage: mozinstall [options] installer 20:10:05 INFO - Options: 20:10:05 INFO - -h, --help show this help message and exit 20:10:05 INFO - -d DEST, --destination=DEST 20:10:05 INFO - Directory to install application into. [default: 20:10:05 INFO - "/builds/slave/test"] 20:10:05 INFO - --app=APP Application being installed. [default: firefox] 20:10:05 INFO - mkdir: /builds/slave/test/build/application 20:10:05 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'] 20:10:05 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 20:10:22 INFO - Reading from file tmpfile_stdout 20:10:22 INFO - Output received: 20:10:22 INFO - /builds/slave/test/build/application/firefox/firefox 20:10:22 INFO - Running post-action listener: _resource_record_post_action 20:10:22 INFO - [mozharness: 2016-11-03 03:10:22.843025Z] Finished install step (success) 20:10:22 INFO - [mozharness: 2016-11-03 03:10:22.843357Z] Running run-tests step. 20:10:22 INFO - Running pre-action listener: _resource_record_pre_action 20:10:22 INFO - Running main action method: run_tests 20:10:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:10:22 INFO - Python 2.7.3 20:10:22 INFO - Return code: 0 20:10:22 INFO - grabbing minidump binary from tooltool 20:10: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')]} 20:10:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c6af60>, '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 0x2c6bd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c6c1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:10:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:10:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:10:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:10:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:10:22 INFO - Return code: 0 20:10:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:10:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:10:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:10:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:10:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:10:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 20:10:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 20:10:22 INFO - Using env: {'DISPLAY': ':0', 20:10:22 INFO - 'HOME': '/home/cltbld', 20:10:22 INFO - 'LANG': 'en_US.UTF-8', 20:10:22 INFO - 'LANGUAGE': 'en_US:en', 20:10:22 INFO - 'LOGNAME': 'cltbld', 20:10:22 INFO - 'MAIL': '/var/mail/cltbld', 20:10:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:10:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:22 INFO - 'MOZ_NO_REMOTE': '1', 20:10:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:10:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:10:22 INFO - 'NO_EM_RESTART': '1', 20:10:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:22 INFO - 'PWD': '/builds/slave/test', 20:10:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:10:22 INFO - 'SHELL': '/bin/bash', 20:10:22 INFO - 'SHLVL': '1', 20:10:22 INFO - 'TERM': 'linux', 20:10:22 INFO - 'TMOUT': '86400', 20:10:22 INFO - 'USER': 'cltbld', 20:10:22 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596', 20:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:22 INFO - '_': '/tools/buildbot/bin/python'} 20:10:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 20:10:23 INFO - mozversion application_buildid: 20161102063812 20:10:23 INFO - mozversion application_changeset: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 20:10:23 INFO - mozversion application_display_name: Nightly 20:10:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:10:23 INFO - mozversion application_name: Firefox 20:10:23 INFO - mozversion application_remotingname: firefox 20:10:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:10:23 INFO - mozversion application_vendor: Mozilla 20:10:23 INFO - mozversion application_version: 52.0a1 20:10:23 INFO - mozversion platform_buildid: 20161102063812 20:10:23 INFO - mozversion platform_changeset: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 20:10:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:10:23 INFO - mozversion platform_version: 52.0a1 20:10:23 INFO - using testdate: 1478142623 20:10:23 INFO - actual date: 1478142623 20:10:23 INFO - starting webserver on 'localhost:37242' 20:10:23 INFO - SUITE-START | Running 2 tests 20:10:23 INFO - TEST-START | dromaeo_css 20:10:23 INFO - operating with platform_type : linux_ 20:10:23 INFO - Initialising browser for dromaeo_css test... 20:10:23 INFO - TEST-INFO | started process 1815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDiA_IS/profile http://localhost:37242/getInfo.html) 20:10:27 INFO - PROCESS | 1815 | __metrics Screen width/height:1600/1200 20:10:27 INFO - PROCESS | 1815 | colorDepth:24 20:10:27 INFO - PROCESS | 1815 | Browser inner width/height: 1024/697 20:10:27 INFO - PROCESS | 1815 | __metrics 20:10:28 INFO - PROCESS | 1815 | 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" 20:10:28 INFO - PROCESS | 1815 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:10:28 INFO - PROCESS | 1815 | console.error: PushService: 20:10:28 INFO - PROCESS | 1815 | stateChangeProcessEnqueue: Error transitioning state 20:10:28 INFO - PROCESS | 1815 | UnknownError 20:10:29 INFO - TEST-INFO | 1815: exit 0 20:10:29 INFO - Browser initialized. 20:10:29 INFO - Running cycle 1/1 for dromaeo_css test... 20:10:29 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDiA_IS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:41 INFO - PROCESS | 1897 | RSS: Main: 172941312 20:10:41 INFO - PROCESS | 1897 | 20:11:32 INFO - PROCESS | 1897 | 1478142692908 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] 20:13:42 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37242/tests/dromaeo/cssquery-ext.html) 20:13:45 INFO - PROCESS | 1897 | RSS: Main: 397955072 20:13:45 INFO - PROCESS | 1897 | 20:16:47 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-ext.html (next: http://localhost:37242/tests/dromaeo/cssquery-jquery.html) 20:16:48 INFO - PROCESS | 1897 | RSS: Main: 289198080 20:16:48 INFO - PROCESS | 1897 | 20:19:50 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37242/tests/dromaeo/cssquery-mootools.html) 20:19:50 INFO - PROCESS | 1897 | RSS: Main: 222437376 20:19:50 INFO - PROCESS | 1897 | 20:22:52 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37242/tests/dromaeo/cssquery-prototype.html) 20:22:53 INFO - PROCESS | 1897 | RSS: Main: 220336128 20:22:53 INFO - PROCESS | 1897 | 20:25:56 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37242/tests/dromaeo/cssquery-yui.html) 20:25:56 INFO - PROCESS | 1897 | RSS: Main: 219078656 20:25:56 INFO - PROCESS | 1897 | 20:28:59 INFO - PROCESS | 1897 | Cycle 1(1): loaded http://localhost:37242/tests/dromaeo/cssquery-yui.html (next: http://localhost:37242/tests/dromaeo/cssquery-dojo.html) 20:29:00 INFO - PROCESS | 1897 | RSS: Main: 320303104 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | __start_tp_report 20:29:00 INFO - PROCESS | 1897 | _x_x_mozilla_page_load 20:29:00 INFO - PROCESS | 1897 | _x_x_mozilla_page_load_details 20:29:00 INFO - PROCESS | 1897 | |i|pagename|runs| 20:29:00 INFO - PROCESS | 1897 | |0;dojo.html;2934.9559756603653;2958.0682085143194;2994.8352840593766;3006.338605506789;3013.8794448222084;5009.69941803492;5074.949250507494;5149.716765577892;5162.767675454605;5267.28891599634;5100.923486147711;5141.794328226866;5164.509371609701;5189.040027594898;5244.5542128919;4731.57415832575;4746.786394612251;4786.784594693229;4804.567588916997;4818.397700287464;4855.854324370257;4860.902781944359;4911.238757992517;4918.237673160648;4964.776585053663;4667.299905014275;4750.786214620351;4756.215224487955;4777.402824646919;4860.684055536397;4695.460022097468;4725.31482934976;4744.644151688637;4763.571278584944;4769.308450274691;4733.148033353995;4735.621150307967;4738.526147385234;4775.474697783276;4809.807607695688;5160.354955630546;5204.453532379083;5206.114960456694;5212.765425555859;5215.791368345262;5362.222477740745;5445.700486473243;5549.056660367747;5576.302962129734;5627.043402621564;5605.691686957216;5633.746481408347;5650.74571644277;5688.459596338341;5701.543876489913;5279.788808447657;5280.20796880471;5285.656432331886;5285.995660824508;5346.358436987586;5556.444355564411;5600.691961942132;5600.719964001783;5748.223989761413;5757.884842303214;5414.729263536808;5422.8373148805595;5428.348598168166;5481.75332110048;5495;5437.918431223535;5483.616146869761;5486.725663716798;5491.752871120809;5495.6153069285565;5297.125974214211;5328.973355133199;5431.101134804839;5540.889182216414;5553.250311208016;5512.062949298629;5515.558755299567;5517.531009864207;5598.86002849932;5699.857503562361;4337.891552711208;4343.2182207203;4344.56554344563;4345.283028300359;4392.670549708785;3843.077661361309;3844.961550384516;3883.825227864753;3899.883003509899;3924.9607503924594;5278.313819203479;5322.707251101149;5339.065663508871;5342.305500285016;5345.679259244458;5597.6641401516035;5641.182028605829;5649.576281778883;5653.434656534314;5654.293213348332;5119.0529751996;5173.715445650525;5359;5364.302640656768;5422.783088676409;4876.097921884392;4891.877703057452;4920.75396230187;4930.309756634074;4966.726830024383;5844.240248767633;5850.678212698257;5853.560982926212;5875.441833025856;5878.235829342244;5452.182172674131;5647.632903861236;5650.406707295675;5652.265205523256;5654.321481422256;5712.143178523254;5751.511121554715;5807.535397168132;5814.244148260699;5816.127580862735;5488.862778430571;5497.8075767347955;5503.11950087984;5503.669779813224;5505.532029777513;5613.887722245614;5629.662220266797;5629.831105066854;5639.88720225585;5640.85897852557;5457.372402173801;5477.068898287221;5478.123500239943;5491.862703432446;5491.972540137273;5449.782008719765;5480.862978425571;5481.095619222783;5484.149956756709;5494.752736126804;5432.239486471976;5433.1035379161995;5435.619506634498;5440.918386224289;5452.154915988028;5416.295881535375;5423.484768946943;5435.130379139318;5507.228987941611;5512.614117011771;5386.622936394571;5386.703731294817;5388.73056347189;5388.919166212589;5452.672839629635 20:29:00 INFO - PROCESS | 1897 | |1;ext.html;590.9763609455622;606.7410123992713;611.6819253987925;613.3712944232161;626.7524327890486;31719.68280317199;34936.650633493686;34960.12599685009;35031.64968350319;39954.60045399545;40053.39919901204;44158.55841441585;44471.77764111183;44520.77739611305;44538;8803.075677053914;8948.552572371387;8965.058668839776;8967.820643587125;8978.191962723353;8072.071711753136;8266.751997440086;8805.559722013906;8900.955495222515;8906.51014194219;8304.252617264445;8348.206920342558;10848.29486083406;11076.058535024533;11111.111111111091;10765.730856728565;10772.08437282828;10805.135589152927;10842.241043126945;10850.45747712619;10624.521896514598;10687.786244275148;10691.411972341519;10693.411862347568;10700.625478108228;41698.58301416977;41920;42039.789801051105;42048.57951420477;42121.1575768486;3534.2578058607724;3830.272248272819;3899.220155968789;3925.725199236055;3951.920961580752;32343.636772533093;32693.50959735606;32738.67261327404;32785.836070819496;32804.70367074306;3835.9616403835885;3876.0116170376373;3880.2433525462548;4037.6366127048414;4040;319999;320176;320871;321916;323391;57973.42026579765;58073;58117.70941145352;58390.41609583935;58410;62096;62173.689131554056;62206.68896655488;62267;62380.68809656014;14640.414383424544;14830.70338593222;14831.40674373013;14865.108093514424;14873.18197499148;10530.420826854443;10535.209859260432;10566.841497377545;10574.788504230026;10655.73360665974;13419.550952428575;15837.762433563506;17497.825021749875;17643.55891102707;17925;5046.798128074909;5792.478676919098;5904.557158213065;5946.43508866657;6035.426634469806;917.3945196170496;980.2550061952967;1056.9682909512726;1128.8306753986803;1136.7555975465314;29991.70008299889;31153.376932461222;31401.214969625486;31415.37169256602;31821.36357272888;1097.401915955806;1113.92202545821;1123.2081382625229;1126.487448211062;1191.237607930925;15801.446949356947;16677.49967500977;16719.247633856507;16748.581285467717;16757.32970681159;447184;450452;450726;451152;451693;48187.518124819006;49530.50469495331;53400.465995340324;54462.18306725402;56380.436195638336;1303.2310936547483;1312.4618906248393;1337.8394592648556;1343.1605246720994;1363.4818768867767;30235;32214.355712885143;32617.34765304728;32802.50796238106;33036.1740956478;50637.74681126571;51180.74409627928;51382;51452.48547514627;51504.48495514997;433795;445725;622273;622700;623137;28070.859645702458;28161.859190703915;28382.716172838835;28421.43157136887;28438.573421398283;46873.76563117299;47197.52802472069;47707.52292477031;48692.26961595508;48761.5123848757;38569.807150964065;38951.415728763524;40219.59780402276;41708.374374385014;42172.78913605535;39792.801035994635;40387.798061009504;40491.39262911118;41636.79181604073;42417.787911060244;39883.40174897321;40574.79712601418;41116;41187.38218926779;41448;33927.327721728136;36484.81757591195;36744.81627591845;36911;37584;27413.45173096567;27754.722452775215;27878.860605696842;27896.86051569729;27946 20:29:00 INFO - PROCESS | 1897 | |2;jquery.html;363.37680877295435;370.83497843459674;383.39997903281363;385.7415531593833;405.3514376996804;14703.852961470382;17886.46340609782;18171.182296796644;18187.99966001871;19416.80583194171;17253.654926901487;17256.913715431438;17321.133943302848;17503;18115.72826407605;5143.897122057557;5369.892602147955;5391.218273350361;5400.162974738902;5405.18922161675;5518.144687573433;5554.694491802969;5569.248151499556;5646.6894320812335;5653;5316.6544174628525;5476.3702174250075;5479.013777520045;5491.945080549203;5493.121100623901;5363.785448582052;5431.619786614939;5510.366307874606;5517.200005999148;5519.503244707996;4911.287863259808;4930.235813448921;4933.1860243059855;4937.876553086166;4938.654294199408;22249.777502224933;22561.097556097735;22729.204477843363;22764.317070487912;22966;2332.5334933013464;2384.618461046224;2430.817688673339;2445.29086564896;2990.386970671007;14026.368813403422;14083.647908802259;14255.358508867126;14814.407423703038;14816.925915370353;1852.3979706595276;1951.1902560437402;1976.1206263212875;1980.0891589868677;2011.215625905898;76088.23911760922;79604;79879;81878.52373145663;83158.51573358427;10499.790004200026;10513.158947284352;10726.356418614909;10943.671689849318;11003.559857605766;14891;15008.699826003418;15013.399464021317;15016.324265407864;15051.322690478955;6986.266442023616;7223.133224013152;7247.094113235846;7426.4430167736555;7465.925340746631;6646.235682896432;6772.52592318533;6941;6966.756163534252;7025.227225005246;3930.253251882133;3992.900177495586;4431.446069241345;4605.240135377625;4658.813647454132;1819.1813683842324;1881.4637828218893;1888.2541396148445;1913.1199648162;1934.5550523379702;886.2909672262165;919.8896132464014;924.1867156901883;975.2685485885586;1002.7443002034435;17401.65196696059;18702.906485467487;18906.52733681669;19168.329108481415;19196.424107276805;961.6634178037631;964.1852634523816;983.8720966274338;1002.5488530161457;1020.357174979758;8377.41358104939;9837.508124593885;10366;10548.367097973993;10567.365958042543;445555;451588;455543;456668;458022;87663.56168219246;87802.56098719465;88532.5573372129;89187.55406222928;91786.54106729424;945.607572857284;1017.97455063624;1039.4542864995788;1045.1690905729963;1046.8272734998793;15933.920330398274;16381.508554743377;16598.25307861125;16612.169391530133;16863.578410539834;42608;42993.355099674154;43340;43445.78277108594;44116;55416.72291638516;56755;56923.71538142283;57884;60211.39788602058;7047.577145371294;7179.641017949186;7518.360939320219;7571.583562904093;7638.770836874903;12584.18202816814;12628.368581570701;13033.826955574044;13119;13763.655908601964;10306.742331441774;10743.086837618577;11327.433628318386;11607.36159511235;11641.76716465649;11152.442377881236;11359.204855660355;11380.829287560859;11429.371384573597;11448.198626096093;6283.3402492737605;7892.447528672938;11266.718332041764;11438.885611143782;11683.941580292385;7121.608311542915;7217.530860494051;7375.299346562068;7574.166841647525;7641.3886889047635;10866.511007004545;11446.82829757571;11925.165238433226;12058.336791476553;12162 20:29:00 INFO - PROCESS | 1897 | |3;mootools.html;1331.5672406467888;1422.0472283570004;1442.8340740814804;1580.754982977639;1759.859211263099;7367.8894816577795;7902.683892644294;8128.105908350084;8144.796380090501;8352.498850069;8320.708775192854;8364.456310339823;8382.03606585243;8384.664613415456;8386.790330241747;2232.10715713715;2254.8985295661732;2255.4812393149587;2263.4680850000236;2266.637338025916;2231.062953559509;2291.140822191678;2296.150424342987;2308.4690521180137;2309.988450057756;2222.7110475638146;2240.7535171131162;2263.0382087612697;2271.4207876991427;2273.022600281878;2212.9004194811273;2234.530748542808;2249.111600917645;2250.6173950428465;2250.966235506469;2223.5775202711434;2248.033345661365;2248.15694114707;2251.0658076898067;2251.3358559225067;8169.183081691784;8226.095129535746;8269.875951860728;8291.33669306454;8296.626651800683;4837.9516204837855;4960.454350021495;4969.975150124263;4983.277424773388;4998.300237966688;3301.7853839500353;3426.4860270959307;3458.7405944554257;3476.548048753671;3488.843002064913;1058.2010582010532;1077.8706555213346;1107.8947499987569;1130.6996953503537;1137.5620386151309;11583.420828958602;11601.535938562447;11661.825072623917;11696.88303116958;11709.648710538613;3987.7009224307712;4225;4250.53244143144;4319.244132276839;4343.587359200871;9210.401323913924;9216.2626989842;9224.907750922404;9225.769355766026;9231.261499080058;4966.677165984272;4972.552470277692;4976.303317535548;4988.077205716954;4992.725400102957;4125.195586860534;4162.958370416318;4300.290452075373;4457.9331310030375;4476.731396116244;3605.5673319201865;3607.1162565171703;3732.757370770891;3762;3808.5239345081864;1118.765059337532;1148.586508856811;1153.0717772193466;1171.2679575265458;1181.3915833345834;825.3850881093504;827;846;933.4352716606492;945.2532499325594;5356;5554.666719996813;5594.888102237933;5663.858403539944;5897.233359663216;925.796324808541;926.3561824531886;936.6253498600614;945.0596656326861;957.1816097236901;3656.5794933582447;3775.244951009809;3859.3439115350457;3891.669208117285;3901.4733011043713;10187.439690817077;10472.319299245524;10569.312994655324;10573.524191411405;10577.471126443646;10422.270441069053;10438.269321147447;10455.320404173704;10456.477176141163;10489.318194317346;7286.70853165878;7588.317051465505;7806.453548251681;8022.79943001418;8024.839503209903;5390.892182156414;5453.700046497481;5515.58633102527;5607.41122182165;5614.354349249888;9092.408993415407;9219.585118669811;9256.861147082664;9359.719208423758;9651.951740241253;9354.95322523383;9603.135717785433;9671;9724.124828765445;9761.023897610183;6881.002254672944;7095.680694368662;7390.778276651709;7620.276073772982;7624.6950121996715;7478.551286922584;7757.612119393895;7761.223877612194;7947.682092716458;7952.840943180989;7257.927420725937;7310.744123955636;7331.926680733124;7393.186749457663;7501.774946751606;7108.573485590881;7198.18675434025;7218.097737782778;7298.160711518243;7480.887786683095;6064.090386441892;6145.385461453819;6149.7847575334645;6159.784407545714;6169.352218017223;4587.8623641290815;4678.368420263221;4688.109259240733;4737.526247375235;4755.524447555217;4456.844010459618;4563.589276965089;4621.399218101626;4644.2801365788355;4779.928301075556 20:29:00 INFO - PROCESS | 1897 | |4;prototype.html;316.10384559773047;316.56788483719714;319.3549030957466;319.5366718258526;319.77935224694966;5202;5217.947820521794;5245.81639642613;5263.763015691309;5265.420803711593;5187.170052791555;5193.272941788153;5204.609654275935;5224.29472021277;5235.738213089349;3875.2636998970247;3982.7411218270863;3997.7013217399967;4004.4794176757127;4008.158286759784;3811.6760075393627;4013.6989725770536;4019.1358857845557;4028.1943611277716;4059.8376064957365;4021.3967904814367;4025.1748391579804;4030.576789437111;4034;4036.4348991141264;3830.71269654777;4014.7791871447057;4050.3924411338094;4051.311277082903;4055.2092341993325;3512.5082488451635;3516.1209697575605;3533.399322115221;3537.4163263061564;3542;5042.445331013586;5048.596112311006;5059.3169922060415;5063.645544811865;5093.541581257705;4469.240229161051;4547.522510136421;4628.44458664959;4643.721376717408;4665.603423708955;5335.706536140511;5362.302900622933;5398.433164517708;5401.540869026137;5402;3860.9806950965344;3867.1298957734566;3871.128995975911;3877.515310586177;3879.7090218233743;5384.569234461194;5699.715014249271;5731.369549349568;5731.942680573183;5768.653880767168;3927.9214415711526;3950.8419663213726;3951.841926322915;3957.1689945111275;3961.8811435656976;4004.1190937993597;4008.158286759755;4021.3565829467143;4037.1118353962083;4057.6145266199665;4642.976785116053;4655.557722016403;4664.580187783115;4671.883202919887;4674.883127921829;5436.238926550286;5470.8937059232485;5496.31296087989;5498.752556134984;5503.12060586623;1359.8844098251561;1369.2469141971876;1374.0656353679594;1376.7865980772997;1385.231196685829;854.8674955381925;884.5046773806689;887.1882227761655;888.2139306713476;890.2655309369795;5153.587712982952;5171.3535808024;5269.394019687785;5284.260203571503;5286.98641774185;5548.445155484419;5548.528375088163;5555.527780138734;5558.6942718150085;5565.332160140809;5058.873528161752;5273.76066624343;5288.471152884681;5311.760970756325;5312.787488500417;2920.97079029207;2953.320736230636;2958.03863744284;2964.584958105867;2969.4061187762536;5790.594658373873;5795.652260864362;5840.445157710011;5905.645661260252;5910.881782364415;5631.63394379364;5687.544996400278;5717.17101020358;5738.139279108083;5753.539716822645;5783.826485205283;5862.94137058624;5865.882682346414;5875.383084776037;5877.471027607536;5382.838514844404;5559.082116669742;5597.132444470951;5605.159226116115;5620.550355971594;5543.556515478671;5554.472325129106;5563.887222555586;5573.944260557505;5587.469190426903;5820.330661973927;5826.76692932278;5848.578581529852;5857.209276747584;5881.7647294107755;3555.3778088833833;3605.963940360563;3627.6758982970496;3646.9635303646623;3647.507586475792;4907.55831975124;4951.306817045688;4958.999805117852;4961.950380496149;4970.900581988412;4895.241237608177;4898.4121905371585;4907.730074845775;4910.680805747615;4930.802767889388;4690.530946905419;4695.018663148993;4815.398075240595;4820.096062055539;4847.127517046965;4800.7599620019555;4803.3995750531185;4811.350467686818;4819.49395313487;4845.951540484691;5254.737263136904;5394.32570928634;5410.404855465817;5467.507924286833;5489.204065410573;5332.040717401879;5341.679499230058;5366.463353664602;5369.48989845964;5385.2191432241325 20:29:00 INFO - PROCESS | 1897 | |5;yui.html;714.2928500784219;764.7208768799389;775.3680750188596;807.8020884883205;840.302548884426;12812.423440945156;13795.793063104062;13836.723265534683;13918.095323803946;13928.303584820756;12527.060470464728;12546;12637;12655.810162847565;12673.30296833676;648.1023782061854;666.7199776094028;783.0290439854597;785.4815821557773;808.6644042722263;794.3684770607352;805.0741647105846;805.5931754464017;805.7341077444429;806.1776987472774;851.2679095977454;854.1885209051395;854.9016863060734;856.4561503445295;856.546030603781;675.8715843989626;692.2558249881347;801.9157988411191;841.4026041510505;841.4194205997873;611.4160976267698;615.818333591587;616.4020899727258;618.0852338538988;620.9813705588795;13331;13356.599302020955;13393.263370514618;13396.598102056856;13400.061995660308;13348.599542013755;13368.732625347537;13503.797443038362;13530.864691353157;13546.661333466644;11421.200515963887;11504.884951150465;11798.882011179865;11997.34014629195;12193.329366884818;4184.797153307102;4312.568743125694;4376.256036473807;4490.7754612269255;4520.2315606347;62620.68689656568;63685.68157159184;64089;64472.3552764471;64527;9561.378510396802;9866.309358344846;9932.453715045598;10080.395176289447;10147.847782283272;18614.348497802785;19236.80763192378;19434.22263109488;20215.29246476366;20575.8971205146;7842.372610191171;8218.876716849252;8304.086550479442;8359.122292159358;8459.069502354736;4478.171538265431;4545.727256364628;4592.357070010201;4598.471175814757;4629.699069560468;3236.336551007038;3320.269540701042;3323.26888084621;3334.7165490933544;3335.0828522156116;566.2214455124205;566.9404712505127;568.457123447107;569.4191924237255;569.9572532060112;642.6754488983033;659.4032400677395;684.9075374824434;719.4424321151046;727.9017332660129;8596;8917.286617070618;9349.48577828226;9426.340156189;9539.3322467428;586.416515567005;653.264839504708;709.6664567653195;729.959852208123;739.1573606089063;4359.760213188305;4469.463664360298;4583.312503124492;4736.384270044872;4821.234834561153;53047.73476132672;77876;89984.5500772505;91963;95394.5230273858;47639.5236047642;48498.515014850105;59696;61549.384506155264;78480;68035.65982170058;69781.3021869785;70492;71023.2897671027;73492.99999999894;8836.602352894264;9106.590203440728;9159.587818548049;9258.953705231701;9300.441973481613;47584;48091.75954120347;48283;48706.512934870196;49420.27029053966;44390.778046110856;64682.67658661676;65447;65535.34464655293;68725.96911046439;11325.546978120783;11678.941605291919;11755.471003804678;11842.585509507126;11887.46506407231;13163.736725265633;13416.530421435593;13432.865671343161;13470.595882123553;13750.449982000608;11337.659870203906;12651.240925544496;13464.932675336953;13698.589042328746;13828.37772300269;12526.617445123155;12666.87333126657;12696.238225706487;12919.547815826401;13295.66760830987;11944.880551194377;11987.40062996864;12010;12041.638750837488;12315.75368492643;10958.945205273923;10982.341059536453;11196.969091127025;11483.942580287045;11515.596954106566;10611.469426528796;10659.8934010662;11143.777124457303;11358.671472348315;11446.885531144582 20:29:00 INFO - PROCESS | 1897 | __end_tp_report 20:29:00 INFO - PROCESS | 1897 | __start_cc_report 20:29:00 INFO - PROCESS | 1897 | _x_x_mozilla_cycle_collect,2322 20:29:00 INFO - PROCESS | 1897 | __end_cc_report 20:29:00 INFO - PROCESS | 1897 | __startTimestamp1478143740234__endTimestamp 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | ------- Summary: start ------- 20:29:00 INFO - PROCESS | 1897 | Number of tests: 6 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#0] dojo.html Cycles:165 Average:5197.17 Median:5416.30 stddev:579.63 (10.7%) stddev-sans-first:553.58 20:29:00 INFO - PROCESS | 1897 | Values: 2935.0 2958.1 2994.8 3006.3 3013.9 5009.7 5074.9 5149.7 5162.8 5267.3 5100.9 5141.8 5164.5 5189.0 5244.6 4731.6 4746.8 4786.8 4804.6 4818.4 4855.9 4860.9 4911.2 4918.2 4964.8 4667.3 4750.8 4756.2 4777.4 4860.7 4695.5 4725.3 4744.6 4763.6 4769.3 4733.1 4735.6 4738.5 4775.5 4809.8 5160.4 5204.5 5206.1 5212.8 5215.8 5362.2 5445.7 5549.1 5576.3 5627.0 5605.7 5633.7 5650.7 5688.5 5701.5 5279.8 5280.2 5285.7 5286.0 5346.4 5556.4 5600.7 5600.7 5748.2 5757.9 5414.7 5422.8 5428.3 5481.8 5495.0 5437.9 5483.6 5486.7 5491.8 5495.6 5297.1 5329.0 5431.1 5540.9 5553.3 5512.1 5515.6 5517.5 5598.9 5699.9 4337.9 4343.2 4344.6 4345.3 4392.7 3843.1 3845.0 3883.8 3899.9 3925.0 5278.3 5322.7 5339.1 5342.3 5345.7 5597.7 5641.2 5649.6 5653.4 5654.3 5119.1 5173.7 5359.0 5364.3 5422.8 4876.1 4891.9 4920.8 4930.3 4966.7 5844.2 5850.7 5853.6 5875.4 5878.2 5452.2 5647.6 5650.4 5652.3 5654.3 5712.1 5751.5 5807.5 5814.2 5816.1 5488.9 5497.8 5503.1 5503.7 5505.5 5613.9 5629.7 5629.8 5639.9 5640.9 5457.4 5477.1 5478.1 5491.9 5492.0 5449.8 5480.9 5481.1 5484.1 5494.8 5432.2 5433.1 5435.6 5440.9 5452.2 5416.3 5423.5 5435.1 5507.2 5512.6 5386.6 5386.7 5388.7 5388.9 5452.7 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#1] ext.html Cycles:180 Average:59701.22 Median:21415.83 stddev:120504.81 (562.7%) stddev-sans-first:120761.13 20:29:00 INFO - PROCESS | 1897 | Values: 591.0 606.7 611.7 613.4 626.8 31719.7 34936.7 34960.1 35031.6 39954.6 40053.4 44158.6 44471.8 44520.8 44538.0 8803.1 8948.6 8965.1 8967.8 8978.2 8072.1 8266.8 8805.6 8901.0 8906.5 8304.3 8348.2 10848.3 11076.1 11111.1 10765.7 10772.1 10805.1 10842.2 10850.5 10624.5 10687.8 10691.4 10693.4 10700.6 41698.6 41920.0 42039.8 42048.6 42121.2 3534.3 3830.3 3899.2 3925.7 3951.9 32343.6 32693.5 32738.7 32785.8 32804.7 3836.0 3876.0 3880.2 4037.6 4040.0 319999.0 320176.0 320871.0 321916.0 323391.0 57973.4 58073.0 58117.7 58390.4 58410.0 62096.0 62173.7 62206.7 62267.0 62380.7 14640.4 14830.7 14831.4 14865.1 14873.2 10530.4 10535.2 10566.8 10574.8 10655.7 13419.6 15837.8 17497.8 17643.6 17925.0 5046.8 5792.5 5904.6 5946.4 6035.4 917.4 980.3 1057.0 1128.8 1136.8 29991.7 31153.4 31401.2 31415.4 31821.4 1097.4 1113.9 1123.2 1126.5 1191.2 15801.4 16677.5 16719.2 16748.6 16757.3 447184.0 450452.0 450726.0 451152.0 451693.0 48187.5 49530.5 53400.5 54462.2 56380.4 1303.2 1312.5 1337.8 1343.2 1363.5 30235.0 32214.4 32617.3 32802.5 33036.2 50637.7 51180.7 51382.0 51452.5 51504.5 433795.0 445725.0 622273.0 622700.0 623137.0 28070.9 28161.9 28382.7 28421.4 28438.6 46873.8 47197.5 47707.5 48692.3 48761.5 38569.8 38951.4 40219.6 41708.4 42172.8 39792.8 40387.8 40491.4 41636.8 42417.8 39883.4 40574.8 41116.0 41187.4 41448.0 33927.3 36484.8 36744.8 36911.0 37584.0 27413.5 27754.7 27878.9 27896.9 27946.0 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#2] jquery.html Cycles:180 Average:27583.10 Median:384.57 stddev:74975.68 (19495.9%) stddev-sans-first:75158.15 20:29:00 INFO - PROCESS | 1897 | Values: 363.4 370.8 383.4 385.7 405.4 14703.9 17886.5 18171.2 18188.0 19416.8 17253.7 17256.9 17321.1 17503.0 18115.7 5143.9 5369.9 5391.2 5400.2 5405.2 5518.1 5554.7 5569.2 5646.7 5653.0 5316.7 5476.4 5479.0 5491.9 5493.1 5363.8 5431.6 5510.4 5517.2 5519.5 4911.3 4930.2 4933.2 4937.9 4938.7 22249.8 22561.1 22729.2 22764.3 22966.0 2332.5 2384.6 2430.8 2445.3 2990.4 14026.4 14083.6 14255.4 14814.4 14816.9 1852.4 1951.2 1976.1 1980.1 2011.2 76088.2 79604.0 79879.0 81878.5 83158.5 10499.8 10513.2 10726.4 10943.7 11003.6 14891.0 15008.7 15013.4 15016.3 15051.3 6986.3 7223.1 7247.1 7426.4 7465.9 6646.2 6772.5 6941.0 6966.8 7025.2 3930.3 3992.9 4431.4 4605.2 4658.8 1819.2 1881.5 1888.3 1913.1 1934.6 886.3 919.9 924.2 975.3 1002.7 17401.7 18702.9 18906.5 19168.3 19196.4 961.7 964.2 983.9 1002.5 1020.4 8377.4 9837.5 10366.0 10548.4 10567.4 445555.0 451588.0 455543.0 456668.0 458022.0 87663.6 87802.6 88532.6 89187.6 91786.5 945.6 1018.0 1039.5 1045.2 1046.8 15933.9 16381.5 16598.3 16612.2 16863.6 42608.0 42993.4 43340.0 43445.8 44116.0 55416.7 56755.0 56923.7 57884.0 60211.4 7047.6 7179.6 7518.4 7571.6 7638.8 12584.2 12628.4 13033.8 13119.0 13763.7 10306.7 10743.1 11327.4 11607.4 11641.8 11152.4 11359.2 11380.8 11429.4 11448.2 6283.3 7892.4 11266.7 11438.9 11683.9 7121.6 7217.5 7375.3 7574.2 7641.4 10866.5 11446.8 11925.2 12058.3 12162.0 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#3] mootools.html Cycles:180 Average:5434.53 Median:4899.20 stddev:3080.84 (62.9%) stddev-sans-first:3074.06 20:29:00 INFO - PROCESS | 1897 | Values: 1331.6 1422.0 1442.8 1580.8 1759.9 7367.9 7902.7 8128.1 8144.8 8352.5 8320.7 8364.5 8382.0 8384.7 8386.8 2232.1 2254.9 2255.5 2263.5 2266.6 2231.1 2291.1 2296.2 2308.5 2310.0 2222.7 2240.8 2263.0 2271.4 2273.0 2212.9 2234.5 2249.1 2250.6 2251.0 2223.6 2248.0 2248.2 2251.1 2251.3 8169.2 8226.1 8269.9 8291.3 8296.6 4838.0 4960.5 4970.0 4983.3 4998.3 3301.8 3426.5 3458.7 3476.5 3488.8 1058.2 1077.9 1107.9 1130.7 1137.6 11583.4 11601.5 11661.8 11696.9 11709.6 3987.7 4225.0 4250.5 4319.2 4343.6 9210.4 9216.3 9224.9 9225.8 9231.3 4966.7 4972.6 4976.3 4988.1 4992.7 4125.2 4163.0 4300.3 4457.9 4476.7 3605.6 3607.1 3732.8 3762.0 3808.5 1118.8 1148.6 1153.1 1171.3 1181.4 825.4 827.0 846.0 933.4 945.3 5356.0 5554.7 5594.9 5663.9 5897.2 925.8 926.4 936.6 945.1 957.2 3656.6 3775.2 3859.3 3891.7 3901.5 10187.4 10472.3 10569.3 10573.5 10577.5 10422.3 10438.3 10455.3 10456.5 10489.3 7286.7 7588.3 7806.5 8022.8 8024.8 5390.9 5453.7 5515.6 5607.4 5614.4 9092.4 9219.6 9256.9 9359.7 9652.0 9355.0 9603.1 9671.0 9724.1 9761.0 6881.0 7095.7 7390.8 7620.3 7624.7 7478.6 7757.6 7761.2 7947.7 7952.8 7257.9 7310.7 7331.9 7393.2 7501.8 7108.6 7198.2 7218.1 7298.2 7480.9 6064.1 6145.4 6149.8 6159.8 6169.4 4587.9 4678.4 4688.1 4737.5 4755.5 4456.8 4563.6 4621.4 4644.3 4779.9 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#4] prototype.html Cycles:180 Average:4511.52 Median:5045.52 stddev:1341.79 (26.6%) stddev-sans-first:1308.08 20:29:00 INFO - PROCESS | 1897 | Values: 316.1 316.6 319.4 319.5 319.8 5202.0 5217.9 5245.8 5263.8 5265.4 5187.2 5193.3 5204.6 5224.3 5235.7 3875.3 3982.7 3997.7 4004.5 4008.2 3811.7 4013.7 4019.1 4028.2 4059.8 4021.4 4025.2 4030.6 4034.0 4036.4 3830.7 4014.8 4050.4 4051.3 4055.2 3512.5 3516.1 3533.4 3537.4 3542.0 5042.4 5048.6 5059.3 5063.6 5093.5 4469.2 4547.5 4628.4 4643.7 4665.6 5335.7 5362.3 5398.4 5401.5 5402.0 3861.0 3867.1 3871.1 3877.5 3879.7 5384.6 5699.7 5731.4 5731.9 5768.7 3927.9 3950.8 3951.8 3957.2 3961.9 4004.1 4008.2 4021.4 4037.1 4057.6 4643.0 4655.6 4664.6 4671.9 4674.9 5436.2 5470.9 5496.3 5498.8 5503.1 1359.9 1369.2 1374.1 1376.8 1385.2 854.9 884.5 887.2 888.2 890.3 5153.6 5171.4 5269.4 5284.3 5287.0 5548.4 5548.5 5555.5 5558.7 5565.3 5058.9 5273.8 5288.5 5311.8 5312.8 2921.0 2953.3 2958.0 2964.6 2969.4 5790.6 5795.7 5840.4 5905.6 5910.9 5631.6 5687.5 5717.2 5738.1 5753.5 5783.8 5862.9 5865.9 5875.4 5877.5 5382.8 5559.1 5597.1 5605.2 5620.6 5543.6 5554.5 5563.9 5573.9 5587.5 5820.3 5826.8 5848.6 5857.2 5881.8 3555.4 3606.0 3627.7 3647.0 3647.5 4907.6 4951.3 4959.0 4962.0 4970.9 4895.2 4898.4 4907.7 4910.7 4930.8 4690.5 4695.0 4815.4 4820.1 4847.1 4800.8 4803.4 4811.4 4819.5 4846.0 5254.7 5394.3 5410.4 5467.5 5489.2 5332.0 5341.7 5366.5 5369.5 5385.2 20:29:00 INFO - PROCESS | 1897 | 20:29:00 INFO - PROCESS | 1897 | [#5] yui.html Cycles:180 Average:17084.49 Median:26552.12 stddev:22430.03 (84.5%) stddev-sans-first:22459.27 20:29:00 INFO - PROCESS | 1897 | Values: 714.3 764.7 775.4 807.8 840.3 12812.4 13795.8 13836.7 13918.1 13928.3 12527.1 12546.0 12637.0 12655.8 12673.3 648.1 666.7 783.0 785.5 808.7 794.4 805.1 805.6 805.7 806.2 851.3 854.2 854.9 856.5 856.5 675.9 692.3 801.9 841.4 841.4 611.4 615.8 616.4 618.1 621.0 13331.0 13356.6 13393.3 13396.6 13400.1 13348.6 13368.7 13503.8 13530.9 13546.7 11421.2 11504.9 11798.9 11997.3 12193.3 4184.8 4312.6 4376.3 4490.8 4520.2 62620.7 63685.7 64089.0 64472.4 64527.0 9561.4 9866.3 9932.5 10080.4 10147.8 18614.3 19236.8 19434.2 20215.3 20575.9 7842.4 8218.9 8304.1 8359.1 8459.1 4478.2 4545.7 4592.4 4598.5 4629.7 3236.3 3320.3 3323.3 3334.7 3335.1 566.2 566.9 568.5 569.4 570.0 642.7 659.4 684.9 719.4 727.9 8596.0 8917.3 9349.5 9426.3 9539.3 586.4 653.3 709.7 730.0 739.2 4359.8 4469.5 4583.3 4736.4 4821.2 53047.7 77876.0 89984.6 91963.0 95394.5 47639.5 48498.5 59696.0 61549.4 78480.0 68035.7 69781.3 70492.0 71023.3 73493.0 8836.6 9106.6 9159.6 9259.0 9300.4 47584.0 48091.8 48283.0 48706.5 49420.3 44390.8 64682.7 65447.0 65535.3 68726.0 11325.5 11678.9 11755.5 11842.6 11887.5 13163.7 13416.5 13432.9 13470.6 13750.4 11337.7 12651.2 13464.9 13698.6 13828.4 12526.6 12666.9 12696.2 12919.5 13295.7 11944.9 11987.4 12010.0 12041.6 12315.8 10958.9 10982.3 11197.0 11483.9 11515.6 10611.5 10659.9 11143.8 11358.7 11446.9 20:29:00 INFO - PROCESS | 1897 | -------- Summary: end -------- 20:29:00 INFO - PROCESS | 1897 | 20:29:01 INFO - TEST-INFO | 1897: exit 0 20:29:01 INFO - TEST-OK | dromaeo_css | took 1118077ms 20:29:01 INFO - TEST-START | kraken 20:29:01 INFO - operating with platform_type : linux_ 20:29:01 INFO - Initialising browser for kraken test... 20:29:01 INFO - TEST-INFO | started process 2204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDYpAG/profile http://localhost:37242/getInfo.html) 20:29:05 INFO - PROCESS | 2204 | __metrics Screen width/height:1600/1200 20:29:05 INFO - PROCESS | 2204 | colorDepth:24 20:29:05 INFO - PROCESS | 2204 | Browser inner width/height: 1024/697 20:29:05 INFO - PROCESS | 2204 | __metrics 20:29:06 INFO - PROCESS | 2204 | 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" 20:29:06 INFO - PROCESS | 2204 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:29:06 INFO - PROCESS | 2204 | console.error: PushService: 20:29:06 INFO - PROCESS | 2204 | stateChangeProcessEnqueue: Error transitioning state 20:29:06 INFO - PROCESS | 2204 | UnknownError 20:29:07 INFO - TEST-INFO | 2204: exit 0 20:29:07 INFO - Browser initialized. 20:29:07 INFO - Running cycle 1/1 for kraken test... 20:29:07 INFO - TEST-INFO | started process 2287 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDYpAG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:29:19 INFO - PROCESS | 2287 | RSS: Main: 173076480 20:29:19 INFO - PROCESS | 2287 | 20:29:22 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37242/tests/kraken/driver.html?testName=audio-beat-detection) 20:29:22 INFO - PROCESS | 2287 | RSS: Main: 229867520 20:29:22 INFO - PROCESS | 2287 | 20:29:25 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37242/tests/kraken/driver.html?testName=audio-dft) 20:29:26 INFO - PROCESS | 2287 | RSS: Main: 216727552 20:29:26 INFO - PROCESS | 2287 | 20:29:29 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37242/tests/kraken/driver.html?testName=audio-fft) 20:29:29 INFO - PROCESS | 2287 | RSS: Main: 298160128 20:29:29 INFO - PROCESS | 2287 | 20:29:32 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37242/tests/kraken/driver.html?testName=audio-oscillator) 20:29:32 INFO - PROCESS | 2287 | RSS: Main: 207130624 20:29:32 INFO - PROCESS | 2287 | 20:29:34 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:29:35 INFO - PROCESS | 2287 | RSS: Main: 212807680 20:29:35 INFO - PROCESS | 2287 | 20:29:38 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37242/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:38 INFO - PROCESS | 2287 | RSS: Main: 248422400 20:29:38 INFO - PROCESS | 2287 | 20:29:42 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37242/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:42 INFO - PROCESS | 2287 | RSS: Main: 247115776 20:29:42 INFO - PROCESS | 2287 | 20:29:45 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37242/tests/kraken/driver.html?testName=json-parse-financial) 20:29:46 INFO - PROCESS | 2287 | RSS: Main: 246292480 20:29:46 INFO - PROCESS | 2287 | 20:29:47 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:29:48 INFO - PROCESS | 2287 | RSS: Main: 208928768 20:29:48 INFO - PROCESS | 2287 | 20:29:49 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:29:50 INFO - PROCESS | 2287 | RSS: Main: 215986176 20:29:50 INFO - PROCESS | 2287 | 20:29:52 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:29:53 INFO - PROCESS | 2287 | RSS: Main: 225796096 20:29:53 INFO - PROCESS | 2287 | 20:29:55 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:29:56 INFO - PROCESS | 2287 | RSS: Main: 234016768 20:29:56 INFO - PROCESS | 2287 | 20:29:59 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:59 INFO - PROCESS | 2287 | RSS: Main: 231489536 20:29:59 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | Cycle 1(1): loaded http://localhost:37242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37242/tests/kraken/driver.html?testName=ai-astar) 20:30:01 INFO - PROCESS | 2287 | RSS: Main: 282005504 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | __start_tp_report 20:30:01 INFO - PROCESS | 2287 | _x_x_mozilla_page_load 20:30:01 INFO - PROCESS | 2287 | _x_x_mozilla_page_load_details 20:30:01 INFO - PROCESS | 2287 | |i|pagename|runs| 20:30:01 INFO - PROCESS | 2287 | |0;ai-astar;105;112;112;110;112;112;112;111;112;115 20:30:01 INFO - PROCESS | 2287 | |1;audio-beat-detection;148;128;122;168;123;122;120;122;147;127 20:30:01 INFO - PROCESS | 2287 | |2;audio-dft;161;160;161;161;159;161;160;160;160;160 20:30:01 INFO - PROCESS | 2287 | |3;audio-fft;75;75;74;113;75;77;76;76;110;75 20:30:01 INFO - PROCESS | 2287 | |4;audio-oscillator;83;82;124;83;83;81;119;82;82;115 20:30:01 INFO - PROCESS | 2287 | |5;imaging-gaussian-blur;117;117;116;116;117;116;116;116;116;116 20:30:01 INFO - PROCESS | 2287 | |6;imaging-darkroom;146;145;146;147;146;145;146;147;145;146 20:30:01 INFO - PROCESS | 2287 | |7;imaging-desaturate;86;84;85;84;84;85;85;84;84;84 20:30:01 INFO - PROCESS | 2287 | |8;json-parse-financial;51;51;52;51;51;52;52;53;52;52 20:30:01 INFO - PROCESS | 2287 | |9;json-stringify-tinderbox;60;60;61;61;61;60;60;61;61;61 20:30:01 INFO - PROCESS | 2287 | |10;stanford-crypto-aes;88;80;80;79;79;80;87;83;78;80 20:30:01 INFO - PROCESS | 2287 | |11;stanford-crypto-ccm;153;139;127;130;135;132;133;126;169;140 20:30:01 INFO - PROCESS | 2287 | |12;stanford-crypto-pbkdf2;163;163;163;165;170;164;163;168;163;165 20:30:01 INFO - PROCESS | 2287 | |13;stanford-crypto-sha256-iterative;64;65;64;65;65;67;63;64;63;62 20:30:01 INFO - PROCESS | 2287 | __end_tp_report 20:30:01 INFO - PROCESS | 2287 | __start_cc_report 20:30:01 INFO - PROCESS | 2287 | _x_x_mozilla_cycle_collect,1667 20:30:01 INFO - PROCESS | 2287 | __end_cc_report 20:30:01 INFO - PROCESS | 2287 | __startTimestamp1478143801781__endTimestamp 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | ------- Summary: start ------- 20:30:01 INFO - PROCESS | 2287 | Number of tests: 14 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.54 (2.3%) stddev-sans-first:1.32 20:30:01 INFO - PROCESS | 2287 | Values: 105.0 112.0 112.0 110.0 112.0 112.0 112.0 111.0 112.0 115.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:127.50 stddev:16.12 (12.6%) stddev-sans-first:16.12 20:30:01 INFO - PROCESS | 2287 | Values: 148.0 128.0 122.0 168.0 123.0 122.0 120.0 122.0 147.0 127.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 20:30:01 INFO - PROCESS | 2287 | Values: 161.0 160.0 161.0 161.0 159.0 161.0 160.0 160.0 160.0 160.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:15.27 (20.4%) stddev-sans-first:15.95 20:30:01 INFO - PROCESS | 2287 | Values: 75.0 75.0 74.0 113.0 75.0 77.0 76.0 76.0 110.0 75.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:82.00 stddev:18.03 (22.0%) stddev-sans-first:18.73 20:30:01 INFO - PROCESS | 2287 | Values: 83.0 82.0 124.0 83.0 83.0 81.0 119.0 82.0 82.0 115.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 20:30:01 INFO - PROCESS | 2287 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 116.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 20:30:01 INFO - PROCESS | 2287 | Values: 146.0 145.0 146.0 147.0 146.0 145.0 146.0 147.0 145.0 146.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:0.71 (0.8%) stddev-sans-first:0.50 20:30:01 INFO - PROCESS | 2287 | Values: 86.0 84.0 85.0 84.0 84.0 85.0 85.0 84.0 84.0 84.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 20:30:01 INFO - PROCESS | 2287 | Values: 51.0 51.0 52.0 51.0 51.0 52.0 52.0 53.0 52.0 52.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 20:30:01 INFO - PROCESS | 2287 | Values: 60.0 60.0 61.0 61.0 61.0 60.0 60.0 61.0 61.0 61.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:80.00 stddev:3.47 (4.3%) stddev-sans-first:2.74 20:30:01 INFO - PROCESS | 2287 | Values: 88.0 80.0 80.0 79.0 79.0 80.0 87.0 83.0 78.0 80.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:137.00 stddev:13.28 (9.7%) stddev-sans-first:13.00 20:30:01 INFO - PROCESS | 2287 | Values: 153.0 139.0 127.0 130.0 135.0 132.0 133.0 126.0 169.0 140.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:164.50 stddev:2.45 (1.5%) stddev-sans-first:2.52 20:30:01 INFO - PROCESS | 2287 | Values: 163.0 163.0 163.0 165.0 170.0 164.0 163.0 168.0 163.0 165.0 20:30:01 INFO - PROCESS | 2287 | 20:30:01 INFO - PROCESS | 2287 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.40 (2.2%) stddev-sans-first:1.48 20:30:01 INFO - PROCESS | 2287 | Values: 64.0 65.0 64.0 65.0 65.0 67.0 63.0 64.0 63.0 62.0 20:30:01 INFO - PROCESS | 2287 | -------- Summary: end -------- 20:30:01 INFO - PROCESS | 2287 | 20:30:02 INFO - TEST-INFO | 2287: exit 0 20:30:03 INFO - TEST-OK | kraken | took 61845ms 20:30:03 INFO - SUITE-END | took 1179s 20:30:03 INFO - Completed test suite (00:19:40) 20:30:03 INFO - javascript benchmark 20:30:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2934.9559756603653, 2958.0682085143194, 2994.8352840593766, 3006.338605506789, 3013.8794448222084, 5009.69941803492, 5074.949250507494, 5149.716765577892, 5162.767675454605, 5267.28891599634, 5100.923486147711, 5141.794328226866, 5164.509371609701, 5189.040027594898, 5244.5542128919, 4731.57415832575, 4746.786394612251, 4786.784594693229, 4804.567588916997, 4818.397700287464, 4855.854324370257, 4860.902781944359, 4911.238757992517, 4918.237673160648, 4964.776585053663, 4667.299905014275, 4750.786214620351, 4756.215224487955, 4777.402824646919, 4860.684055536397, 4695.460022097468, 4725.31482934976, 4744.644151688637, 4763.571278584944, 4769.308450274691, 4733.148033353995, 4735.621150307967, 4738.526147385234, 4775.474697783276, 4809.807607695688, 5160.354955630546, 5204.453532379083, 5206.114960456694, 5212.765425555859, 5215.791368345262, 5362.222477740745, 5445.700486473243, 5549.056660367747, 5576.302962129734, 5627.043402621564, 5605.691686957216, 5633.746481408347, 5650.74571644277, 5688.459596338341, 5701.543876489913, 5279.788808447657, 5280.20796880471, 5285.656432331886, 5285.995660824508, 5346.358436987586, 5556.444355564411, 5600.691961942132, 5600.719964001783, 5748.223989761413, 5757.884842303214, 5414.729263536808, 5422.8373148805595, 5428.348598168166, 5481.75332110048, 5495.0, 5437.918431223535, 5483.616146869761, 5486.725663716798, 5491.752871120809, 5495.6153069285565, 5297.125974214211, 5328.973355133199, 5431.101134804839, 5540.889182216414, 5553.250311208016, 5512.062949298629, 5515.558755299567, 5517.531009864207, 5598.86002849932, 5699.857503562361, 4337.891552711208, 4343.2182207203, 4344.56554344563, 4345.283028300359, 4392.670549708785, 3843.077661361309, 3844.961550384516, 3883.825227864753, 3899.883003509899, 3924.9607503924594, 5278.313819203479, 5322.707251101149, 5339.065663508871, 5342.305500285016, 5345.679259244458, 5597.6641401516035, 5641.182028605829, 5649.576281778883, 5653.434656534314, 5654.293213348332, 5119.0529751996, 5173.715445650525, 5359.0, 5364.302640656768, 5422.783088676409, 4876.097921884392, 4891.877703057452, 4920.75396230187, 4930.309756634074, 4966.726830024383, 5844.240248767633, 5850.678212698257, 5853.560982926212, 5875.441833025856, 5878.235829342244, 5452.182172674131, 5647.632903861236, 5650.406707295675, 5652.265205523256, 5654.321481422256, 5712.143178523254, 5751.511121554715, 5807.535397168132, 5814.244148260699, 5816.127580862735, 5488.862778430571, 5497.8075767347955, 5503.11950087984, 5503.669779813224, 5505.532029777513, 5613.887722245614, 5629.662220266797, 5629.831105066854, 5639.88720225585, 5640.85897852557, 5457.372402173801, 5477.068898287221, 5478.123500239943, 5491.862703432446, 5491.972540137273, 5449.782008719765, 5480.862978425571, 5481.095619222783, 5484.149956756709, 5494.752736126804, 5432.239486471976, 5433.1035379161995, 5435.619506634498, 5440.918386224289, 5452.154915988028, 5416.295881535375, 5423.484768946943, 5435.130379139318, 5507.228987941611, 5512.614117011771, 5386.622936394571, 5386.703731294817, 5388.73056347189, 5388.919166212589, 5452.672839629635], "value": 5158.324550225461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.9763609455622, 606.7410123992713, 611.6819253987925, 613.3712944232161, 626.7524327890486, 31719.68280317199, 34936.650633493686, 34960.12599685009, 35031.64968350319, 39954.60045399545, 40053.39919901204, 44158.55841441585, 44471.77764111183, 44520.77739611305, 44538.0, 8803.075677053914, 8948.552572371387, 8965.058668839776, 8967.820643587125, 8978.191962723353, 8072.071711753136, 8266.751997440086, 8805.559722013906, 8900.955495222515, 8906.51014194219, 8304.252617264445, 8348.206920342558, 10848.29486083406, 11076.058535024533, 11111.111111111091, 10765.730856728565, 10772.08437282828, 10805.135589152927, 10842.241043126945, 10850.45747712619, 10624.521896514598, 10687.786244275148, 10691.411972341519, 10693.411862347568, 10700.625478108228, 41698.58301416977, 41920.0, 42039.789801051105, 42048.57951420477, 42121.1575768486, 3534.2578058607724, 3830.272248272819, 3899.220155968789, 3925.725199236055, 3951.920961580752, 32343.636772533093, 32693.50959735606, 32738.67261327404, 32785.836070819496, 32804.70367074306, 3835.9616403835885, 3876.0116170376373, 3880.2433525462548, 4037.6366127048414, 4040.0, 319999.0, 320176.0, 320871.0, 321916.0, 323391.0, 57973.42026579765, 58073.0, 58117.70941145352, 58390.41609583935, 58410.0, 62096.0, 62173.689131554056, 62206.68896655488, 62267.0, 62380.68809656014, 14640.414383424544, 14830.70338593222, 14831.40674373013, 14865.108093514424, 14873.18197499148, 10530.420826854443, 10535.209859260432, 10566.841497377545, 10574.788504230026, 10655.73360665974, 13419.550952428575, 15837.762433563506, 17497.825021749875, 17643.55891102707, 17925.0, 5046.798128074909, 5792.478676919098, 5904.557158213065, 5946.43508866657, 6035.426634469806, 917.3945196170496, 980.2550061952967, 1056.9682909512726, 1128.8306753986803, 1136.7555975465314, 29991.70008299889, 31153.376932461222, 31401.214969625486, 31415.37169256602, 31821.36357272888, 1097.401915955806, 1113.92202545821, 1123.2081382625229, 1126.487448211062, 1191.237607930925, 15801.446949356947, 16677.49967500977, 16719.247633856507, 16748.581285467717, 16757.32970681159, 447184.0, 450452.0, 450726.0, 451152.0, 451693.0, 48187.518124819006, 49530.50469495331, 53400.465995340324, 54462.18306725402, 56380.436195638336, 1303.2310936547483, 1312.4618906248393, 1337.8394592648556, 1343.1605246720994, 1363.4818768867767, 30235.0, 32214.355712885143, 32617.34765304728, 32802.50796238106, 33036.1740956478, 50637.74681126571, 51180.74409627928, 51382.0, 51452.48547514627, 51504.48495514997, 433795.0, 445725.0, 622273.0, 622700.0, 623137.0, 28070.859645702458, 28161.859190703915, 28382.716172838835, 28421.43157136887, 28438.573421398283, 46873.76563117299, 47197.52802472069, 47707.52292477031, 48692.26961595508, 48761.5123848757, 38569.807150964065, 38951.415728763524, 40219.59780402276, 41708.374374385014, 42172.78913605535, 39792.801035994635, 40387.798061009504, 40491.39262911118, 41636.79181604073, 42417.787911060244, 39883.40174897321, 40574.79712601418, 41116.0, 41187.38218926779, 41448.0, 33927.327721728136, 36484.81757591195, 36744.81627591845, 36911.0, 37584.0, 27413.45173096567, 27754.722452775215, 27878.860605696842, 27896.86051569729, 27946.0], "value": 19790.803334577635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.37680877295435, 370.83497843459674, 383.39997903281363, 385.7415531593833, 405.3514376996804, 14703.852961470382, 17886.46340609782, 18171.182296796644, 18187.99966001871, 19416.80583194171, 17253.654926901487, 17256.913715431438, 17321.133943302848, 17503.0, 18115.72826407605, 5143.897122057557, 5369.892602147955, 5391.218273350361, 5400.162974738902, 5405.18922161675, 5518.144687573433, 5554.694491802969, 5569.248151499556, 5646.6894320812335, 5653.0, 5316.6544174628525, 5476.3702174250075, 5479.013777520045, 5491.945080549203, 5493.121100623901, 5363.785448582052, 5431.619786614939, 5510.366307874606, 5517.200005999148, 5519.503244707996, 4911.287863259808, 4930.235813448921, 4933.1860243059855, 4937.876553086166, 4938.654294199408, 22249.777502224933, 22561.097556097735, 22729.204477843363, 22764.317070487912, 22966.0, 2332.5334933013464, 2384.618461046224, 2430.817688673339, 2445.29086564896, 2990.386970671007, 14026.368813403422, 14083.647908802259, 14255.358508867126, 14814.407423703038, 14816.925915370353, 1852.3979706595276, 1951.1902560437402, 1976.1206263212875, 1980.0891589868677, 2011.215625905898, 76088.23911760922, 79604.0, 79879.0, 81878.52373145663, 83158.51573358427, 10499.790004200026, 10513.158947284352, 10726.356418614909, 10943.671689849318, 11003.559857605766, 14891.0, 15008.699826003418, 15013.399464021317, 15016.324265407864, 15051.322690478955, 6986.266442023616, 7223.133224013152, 7247.094113235846, 7426.4430167736555, 7465.925340746631, 6646.235682896432, 6772.52592318533, 6941.0, 6966.756163534252, 7025.227225005246, 3930.253251882133, 3992.900177495586, 4431.446069241345, 4605.240135377625, 4658.813647454132, 1819.1813683842324, 1881.4637828218893, 1888.2541396148445, 1913.1199648162, 1934.5550523379702, 886.2909672262165, 919.8896132464014, 924.1867156901883, 975.2685485885586, 1002.7443002034435, 17401.65196696059, 18702.906485467487, 18906.52733681669, 19168.329108481415, 19196.424107276805, 961.6634178037631, 964.1852634523816, 983.8720966274338, 1002.5488530161457, 1020.357174979758, 8377.41358104939, 9837.508124593885, 10366.0, 10548.367097973993, 10567.365958042543, 445555.0, 451588.0, 455543.0, 456668.0, 458022.0, 87663.56168219246, 87802.56098719465, 88532.5573372129, 89187.55406222928, 91786.54106729424, 945.607572857284, 1017.97455063624, 1039.4542864995788, 1045.1690905729963, 1046.8272734998793, 15933.920330398274, 16381.508554743377, 16598.25307861125, 16612.169391530133, 16863.578410539834, 42608.0, 42993.355099674154, 43340.0, 43445.78277108594, 44116.0, 55416.72291638516, 56755.0, 56923.71538142283, 57884.0, 60211.39788602058, 7047.577145371294, 7179.641017949186, 7518.360939320219, 7571.583562904093, 7638.770836874903, 12584.18202816814, 12628.368581570701, 13033.826955574044, 13119.0, 13763.655908601964, 10306.742331441774, 10743.086837618577, 11327.433628318386, 11607.36159511235, 11641.76716465649, 11152.442377881236, 11359.204855660355, 11380.829287560859, 11429.371384573597, 11448.198626096093, 6283.3402492737605, 7892.447528672938, 11266.718332041764, 11438.885611143782, 11683.941580292385, 7121.608311542915, 7217.530860494051, 7375.299346562068, 7574.166841647525, 7641.3886889047635, 10866.511007004545, 11446.82829757571, 11925.165238433226, 12058.336791476553, 12162.0], "value": 8864.039564560773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.5672406467888, 1422.0472283570004, 1442.8340740814804, 1580.754982977639, 1759.859211263099, 7367.8894816577795, 7902.683892644294, 8128.105908350084, 8144.796380090501, 8352.498850069, 8320.708775192854, 8364.456310339823, 8382.03606585243, 8384.664613415456, 8386.790330241747, 2232.10715713715, 2254.8985295661732, 2255.4812393149587, 2263.4680850000236, 2266.637338025916, 2231.062953559509, 2291.140822191678, 2296.150424342987, 2308.4690521180137, 2309.988450057756, 2222.7110475638146, 2240.7535171131162, 2263.0382087612697, 2271.4207876991427, 2273.022600281878, 2212.9004194811273, 2234.530748542808, 2249.111600917645, 2250.6173950428465, 2250.966235506469, 2223.5775202711434, 2248.033345661365, 2248.15694114707, 2251.0658076898067, 2251.3358559225067, 8169.183081691784, 8226.095129535746, 8269.875951860728, 8291.33669306454, 8296.626651800683, 4837.9516204837855, 4960.454350021495, 4969.975150124263, 4983.277424773388, 4998.300237966688, 3301.7853839500353, 3426.4860270959307, 3458.7405944554257, 3476.548048753671, 3488.843002064913, 1058.2010582010532, 1077.8706555213346, 1107.8947499987569, 1130.6996953503537, 1137.5620386151309, 11583.420828958602, 11601.535938562447, 11661.825072623917, 11696.88303116958, 11709.648710538613, 3987.7009224307712, 4225.0, 4250.53244143144, 4319.244132276839, 4343.587359200871, 9210.401323913924, 9216.2626989842, 9224.907750922404, 9225.769355766026, 9231.261499080058, 4966.677165984272, 4972.552470277692, 4976.303317535548, 4988.077205716954, 4992.725400102957, 4125.195586860534, 4162.958370416318, 4300.290452075373, 4457.9331310030375, 4476.731396116244, 3605.5673319201865, 3607.1162565171703, 3732.757370770891, 3762.0, 3808.5239345081864, 1118.765059337532, 1148.586508856811, 1153.0717772193466, 1171.2679575265458, 1181.3915833345834, 825.3850881093504, 827.0, 846.0, 933.4352716606492, 945.2532499325594, 5356.0, 5554.666719996813, 5594.888102237933, 5663.858403539944, 5897.233359663216, 925.796324808541, 926.3561824531886, 936.6253498600614, 945.0596656326861, 957.1816097236901, 3656.5794933582447, 3775.244951009809, 3859.3439115350457, 3891.669208117285, 3901.4733011043713, 10187.439690817077, 10472.319299245524, 10569.312994655324, 10573.524191411405, 10577.471126443646, 10422.270441069053, 10438.269321147447, 10455.320404173704, 10456.477176141163, 10489.318194317346, 7286.70853165878, 7588.317051465505, 7806.453548251681, 8022.79943001418, 8024.839503209903, 5390.892182156414, 5453.700046497481, 5515.58633102527, 5607.41122182165, 5614.354349249888, 9092.408993415407, 9219.585118669811, 9256.861147082664, 9359.719208423758, 9651.951740241253, 9354.95322523383, 9603.135717785433, 9671.0, 9724.124828765445, 9761.023897610183, 6881.002254672944, 7095.680694368662, 7390.778276651709, 7620.276073772982, 7624.6950121996715, 7478.551286922584, 7757.612119393895, 7761.223877612194, 7947.682092716458, 7952.840943180989, 7257.927420725937, 7310.744123955636, 7331.926680733124, 7393.186749457663, 7501.774946751606, 7108.573485590881, 7198.18675434025, 7218.097737782778, 7298.160711518243, 7480.887786683095, 6064.090386441892, 6145.385461453819, 6149.7847575334645, 6159.784407545714, 6169.352218017223, 4587.8623641290815, 4678.368420263221, 4688.109259240733, 4737.526247375235, 4755.524447555217, 4456.844010459618, 4563.589276965089, 4621.399218101626, 4644.2801365788355, 4779.928301075556], "value": 4368.876228932527, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.10384559773047, 316.56788483719714, 319.3549030957466, 319.5366718258526, 319.77935224694966, 5202.0, 5217.947820521794, 5245.81639642613, 5263.763015691309, 5265.420803711593, 5187.170052791555, 5193.272941788153, 5204.609654275935, 5224.29472021277, 5235.738213089349, 3875.2636998970247, 3982.7411218270863, 3997.7013217399967, 4004.4794176757127, 4008.158286759784, 3811.6760075393627, 4013.6989725770536, 4019.1358857845557, 4028.1943611277716, 4059.8376064957365, 4021.3967904814367, 4025.1748391579804, 4030.576789437111, 4034.0, 4036.4348991141264, 3830.71269654777, 4014.7791871447057, 4050.3924411338094, 4051.311277082903, 4055.2092341993325, 3512.5082488451635, 3516.1209697575605, 3533.399322115221, 3537.4163263061564, 3542.0, 5042.445331013586, 5048.596112311006, 5059.3169922060415, 5063.645544811865, 5093.541581257705, 4469.240229161051, 4547.522510136421, 4628.44458664959, 4643.721376717408, 4665.603423708955, 5335.706536140511, 5362.302900622933, 5398.433164517708, 5401.540869026137, 5402.0, 3860.9806950965344, 3867.1298957734566, 3871.128995975911, 3877.515310586177, 3879.7090218233743, 5384.569234461194, 5699.715014249271, 5731.369549349568, 5731.942680573183, 5768.653880767168, 3927.9214415711526, 3950.8419663213726, 3951.841926322915, 3957.1689945111275, 3961.8811435656976, 4004.1190937993597, 4008.158286759755, 4021.3565829467143, 4037.1118353962083, 4057.6145266199665, 4642.976785116053, 4655.557722016403, 4664.580187783115, 4671.883202919887, 4674.883127921829, 5436.238926550286, 5470.8937059232485, 5496.31296087989, 5498.752556134984, 5503.12060586623, 1359.8844098251561, 1369.2469141971876, 1374.0656353679594, 1376.7865980772997, 1385.231196685829, 854.8674955381925, 884.5046773806689, 887.1882227761655, 888.2139306713476, 890.2655309369795, 5153.587712982952, 5171.3535808024, 5269.394019687785, 5284.260203571503, 5286.98641774185, 5548.445155484419, 5548.528375088163, 5555.527780138734, 5558.6942718150085, 5565.332160140809, 5058.873528161752, 5273.76066624343, 5288.471152884681, 5311.760970756325, 5312.787488500417, 2920.97079029207, 2953.320736230636, 2958.03863744284, 2964.584958105867, 2969.4061187762536, 5790.594658373873, 5795.652260864362, 5840.445157710011, 5905.645661260252, 5910.881782364415, 5631.63394379364, 5687.544996400278, 5717.17101020358, 5738.139279108083, 5753.539716822645, 5783.826485205283, 5862.94137058624, 5865.882682346414, 5875.383084776037, 5877.471027607536, 5382.838514844404, 5559.082116669742, 5597.132444470951, 5605.159226116115, 5620.550355971594, 5543.556515478671, 5554.472325129106, 5563.887222555586, 5573.944260557505, 5587.469190426903, 5820.330661973927, 5826.76692932278, 5848.578581529852, 5857.209276747584, 5881.7647294107755, 3555.3778088833833, 3605.963940360563, 3627.6758982970496, 3646.9635303646623, 3647.507586475792, 4907.55831975124, 4951.306817045688, 4958.999805117852, 4961.950380496149, 4970.900581988412, 4895.241237608177, 4898.4121905371585, 4907.730074845775, 4910.680805747615, 4930.802767889388, 4690.530946905419, 4695.018663148993, 4815.398075240595, 4820.096062055539, 4847.127517046965, 4800.7599620019555, 4803.3995750531185, 4811.350467686818, 4819.49395313487, 4845.951540484691, 5254.737263136904, 5394.32570928634, 5410.404855465817, 5467.507924286833, 5489.204065410573, 5332.040717401879, 5341.679499230058, 5366.463353664602, 5369.48989845964, 5385.2191432241325], "value": 4082.4042993980024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.2928500784219, 764.7208768799389, 775.3680750188596, 807.8020884883205, 840.302548884426, 12812.423440945156, 13795.793063104062, 13836.723265534683, 13918.095323803946, 13928.303584820756, 12527.060470464728, 12546.0, 12637.0, 12655.810162847565, 12673.30296833676, 648.1023782061854, 666.7199776094028, 783.0290439854597, 785.4815821557773, 808.6644042722263, 794.3684770607352, 805.0741647105846, 805.5931754464017, 805.7341077444429, 806.1776987472774, 851.2679095977454, 854.1885209051395, 854.9016863060734, 856.4561503445295, 856.546030603781, 675.8715843989626, 692.2558249881347, 801.9157988411191, 841.4026041510505, 841.4194205997873, 611.4160976267698, 615.818333591587, 616.4020899727258, 618.0852338538988, 620.9813705588795, 13331.0, 13356.599302020955, 13393.263370514618, 13396.598102056856, 13400.061995660308, 13348.599542013755, 13368.732625347537, 13503.797443038362, 13530.864691353157, 13546.661333466644, 11421.200515963887, 11504.884951150465, 11798.882011179865, 11997.34014629195, 12193.329366884818, 4184.797153307102, 4312.568743125694, 4376.256036473807, 4490.7754612269255, 4520.2315606347, 62620.68689656568, 63685.68157159184, 64089.0, 64472.3552764471, 64527.0, 9561.378510396802, 9866.309358344846, 9932.453715045598, 10080.395176289447, 10147.847782283272, 18614.348497802785, 19236.80763192378, 19434.22263109488, 20215.29246476366, 20575.8971205146, 7842.372610191171, 8218.876716849252, 8304.086550479442, 8359.122292159358, 8459.069502354736, 4478.171538265431, 4545.727256364628, 4592.357070010201, 4598.471175814757, 4629.699069560468, 3236.336551007038, 3320.269540701042, 3323.26888084621, 3334.7165490933544, 3335.0828522156116, 566.2214455124205, 566.9404712505127, 568.457123447107, 569.4191924237255, 569.9572532060112, 642.6754488983033, 659.4032400677395, 684.9075374824434, 719.4424321151046, 727.9017332660129, 8596.0, 8917.286617070618, 9349.48577828226, 9426.340156189, 9539.3322467428, 586.416515567005, 653.264839504708, 709.6664567653195, 729.959852208123, 739.1573606089063, 4359.760213188305, 4469.463664360298, 4583.312503124492, 4736.384270044872, 4821.234834561153, 53047.73476132672, 77876.0, 89984.5500772505, 91963.0, 95394.5230273858, 47639.5236047642, 48498.515014850105, 59696.0, 61549.384506155264, 78480.0, 68035.65982170058, 69781.3021869785, 70492.0, 71023.2897671027, 73492.99999999894, 8836.602352894264, 9106.590203440728, 9159.587818548049, 9258.953705231701, 9300.441973481613, 47584.0, 48091.75954120347, 48283.0, 48706.512934870196, 49420.27029053966, 44390.778046110856, 64682.67658661676, 65447.0, 65535.34464655293, 68725.96911046439, 11325.546978120783, 11678.941605291919, 11755.471003804678, 11842.585509507126, 11887.46506407231, 13163.736725265633, 13416.530421435593, 13432.865671343161, 13470.595882123553, 13750.449982000608, 11337.659870203906, 12651.240925544496, 13464.932675336953, 13698.589042328746, 13828.37772300269, 12526.617445123155, 12666.87333126657, 12696.238225706487, 12919.547815826401, 13295.66760830987, 11944.880551194377, 11987.40062996864, 12010.0, 12041.638750837488, 12315.75368492643, 10958.945205273923, 10982.341059536453, 11196.969091127025, 11483.942580287045, 11515.596954106566, 10611.469426528796, 10659.8934010662, 11143.777124457303, 11358.671472348315, 11446.885531144582], "value": 6938.173962006785, "unit": "score"}], "name": "dromaeo_css", "value": 6942.733288837932, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 112.0, 110.0, 112.0, 112.0, 112.0, 111.0, 112.0, 115.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 128.0, 122.0, 168.0, 123.0, 122.0, 120.0, 122.0, 147.0, 127.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 159.0, 161.0, 160.0, 160.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 113.0, 75.0, 77.0, 76.0, 76.0, 110.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 124.0, 83.0, 83.0, 81.0, 119.0, 82.0, 82.0, 115.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0, 147.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 52.0, 51.0, 51.0, 52.0, 52.0, 53.0, 52.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 61.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 80.0, 80.0, 79.0, 79.0, 80.0, 87.0, 83.0, 78.0, 80.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 139.0, 127.0, 130.0, 135.0, 132.0, 133.0, 126.0, 169.0, 140.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 165.0, 170.0, 164.0, 163.0, 168.0, 163.0, 165.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 67.0, 63.0, 64.0, 63.0, 62.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1488.0000000000002, "alertThreshold": 2.0}]} 20:30:03 INFO - Return code: 0 20:30:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:30:03 INFO - # TBPL SUCCESS # 20:30:03 INFO - Running post-action listener: _resource_record_post_action 20:30:03 INFO - [mozharness: 2016-11-03 03:30:03.576960Z] Finished run-tests step (success) 20:30:03 INFO - Running post-run listener: _resource_record_post_run 20:30:03 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 12288; Write bytes: 393068544; Read time: 360; Write time: 269000 20:30:03 INFO - TinderboxPrint: CPU usage
13.1% 20:30:03 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 360 20:30:03 INFO - TinderboxPrint: I/O write bytes / time
393,068,544 / 269,000 20:30:03 INFO - TinderboxPrint: CPU idle
8,297.4 (86.9%) 20:30:03 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 20:30:03 INFO - TinderboxPrint: Swap in / out
0 / 0 20:30:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 102957056; Read time: 172; Write time: 64860 20:30:03 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288968704; Read time: 180; Write time: 204112 20:30:04 INFO - Running post-run listener: _upload_blobber_files 20:30:04 INFO - Blob upload gear active. 20:30:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:30:04 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 20:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:30:04 INFO - (blobuploader) - INFO - Open directory for files ... 20:30:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:30:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:30:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:06 INFO - (blobuploader) - INFO - Done attempting. 20:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:30:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:09 INFO - (blobuploader) - INFO - Done attempting. 20:30:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:30:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:10 INFO - (blobuploader) - INFO - Done attempting. 20:30:10 INFO - (blobuploader) - INFO - Iteration through files over. 20:30:10 INFO - Return code: 0 20:30:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:30:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:30:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/774a4e187a2481b823b90f0a323ff762094367d148985b9f8f0f61210006c4f27faa2bdf17e99b088dd8460ed3fb97b81b7b022c52890218f7f2cdfbe5841f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/005d281e62c5eafd5e1d9d9e1ba51075206754b8c82cf829599b225a1e2f02a03f77e9625efe619114999cfa98a692aa0029d835fb5e2eae9e77656065bbb0e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5574bd953028f3281751a8c2573a8e8e62a1c392de7a9c01b022477c01fc2db56ac216b060150c823472d00928d28053711785d259f0908c2648837d4f55d3db"} 20:30:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:30:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:30:10 INFO - Contents: 20:30:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/774a4e187a2481b823b90f0a323ff762094367d148985b9f8f0f61210006c4f27faa2bdf17e99b088dd8460ed3fb97b81b7b022c52890218f7f2cdfbe5841f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/005d281e62c5eafd5e1d9d9e1ba51075206754b8c82cf829599b225a1e2f02a03f77e9625efe619114999cfa98a692aa0029d835fb5e2eae9e77656065bbb0e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5574bd953028f3281751a8c2573a8e8e62a1c392de7a9c01b022477c01fc2db56ac216b060150c823472d00928d28053711785d259f0908c2648837d4f55d3db"} 20:30:10 INFO - Running post-run listener: copy_logs_to_upload_dir 20:30:10 INFO - Copying logs to upload dir... 20:30:10 INFO - mkdir: /builds/slave/test/build/upload/logs 20:30:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.965204 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-11-02 20:30:10.097660) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:30:10.101788) ========= 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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/774a4e187a2481b823b90f0a323ff762094367d148985b9f8f0f61210006c4f27faa2bdf17e99b088dd8460ed3fb97b81b7b022c52890218f7f2cdfbe5841f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/005d281e62c5eafd5e1d9d9e1ba51075206754b8c82cf829599b225a1e2f02a03f77e9625efe619114999cfa98a692aa0029d835fb5e2eae9e77656065bbb0e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5574bd953028f3281751a8c2573a8e8e62a1c392de7a9c01b022477c01fc2db56ac216b060150c823472d00928d28053711785d259f0908c2648837d4f55d3db"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/774a4e187a2481b823b90f0a323ff762094367d148985b9f8f0f61210006c4f27faa2bdf17e99b088dd8460ed3fb97b81b7b022c52890218f7f2cdfbe5841f28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/005d281e62c5eafd5e1d9d9e1ba51075206754b8c82cf829599b225a1e2f02a03f77e9625efe619114999cfa98a692aa0029d835fb5e2eae9e77656065bbb0e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5574bd953028f3281751a8c2573a8e8e62a1c392de7a9c01b022477c01fc2db56ac216b060150c823472d00928d28053711785d259f0908c2648837d4f55d3db"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-02 20:30:10.127760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:30:10.128122) ========= 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=920470bf432669ad7e3a5387000001ca-1478142569.730606-906994596 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-02 20:30:10.213235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:30:10.213586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-02 20:30:10.213912) ========= ========= Total master_lag: 0.18 =========