builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-037 starttime: 1480558923.9 results: success (0) buildid: 20161130170644 builduid: 37c9028061264b7cbe6277421475b483 revision: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:03.903370) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:03.903890) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:03.904183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:03.921926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:03.922359) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-30 18:22:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 65.1M=0s 2016-11-30 18:22:04 (65.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:04.058090) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:04.058416) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018891 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:04.108649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 18:22:04.108955) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 --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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-30 18:22:04,508 truncating revision to first 12 chars 2016-11-30 18:22:04,509 Setting DEBUG logging. 2016-11-30 18:22:04,509 attempt 1/10 2016-11-30 18:22:04,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/0792dcc4e9a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 18:22:05,649 unpacking tar archive at: graphics-0792dcc4e9a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494023 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 18:22:05.618126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:05.618541) ========= script_repo_revision: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:05.619382) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:05.619842) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 18:22:05.629814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-11-30 18:22:05.630094) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:06 INFO - MultiFileLogger online at 20161130 18:22:06 in /builds/slave/test 18:22:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 18:22:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:06 INFO - {'append_to_log': False, 18:22:06 INFO - 'base_work_dir': '/builds/slave/test', 18:22:06 INFO - 'blob_upload_branch': 'Graphics', 18:22:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:06 INFO - 'branch': 'Graphics', 18:22:06 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:06 INFO - 'config_files': ('talos/linux_config.py',), 18:22:06 INFO - 'default_actions': ('clobber', 18:22:06 INFO - 'read-buildbot-config', 18:22:06 INFO - 'download-and-extract', 18:22:06 INFO - 'populate-webroot', 18:22:06 INFO - 'create-virtualenv', 18:22:06 INFO - 'install', 18:22:06 INFO - 'run-tests'), 18:22:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:06 INFO - 'download_minidump_stackwalk': True, 18:22:06 INFO - 'download_symbols': 'ondemand', 18:22:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:06 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:06 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:06 INFO - 'installer_path': 'installer.exe', 18:22:06 INFO - 'log_level': 'info', 18:22:06 INFO - 'log_name': 'talos', 18:22:06 INFO - 'log_to_console': True, 18:22:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:06 INFO - 'opt_config_files': (), 18:22:06 INFO - 'pip_index': False, 18:22:06 INFO - 'sps_profile': False, 18:22:06 INFO - 'sps_profile_interval': 0, 18:22:06 INFO - 'suite': 'dromaeojs', 18:22:06 INFO - 'system_bits': '32', 18:22:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:06 INFO - 'title': 'talos-linux64-ix-037', 18:22:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:06 INFO - 'use_talos_json': True, 18:22:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:06 INFO - 'work_dir': 'build'} 18:22:06 INFO - [mozharness: 2016-12-01 02:22:06.132581Z] Running clobber step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: clobber 18:22:06 INFO - rmtree: /builds/slave/test/build 18:22:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:06 INFO - Running post-action listener: _resource_record_post_action 18:22:06 INFO - [mozharness: 2016-12-01 02:22:06.597602Z] Finished clobber step (success) 18:22:06 INFO - [mozharness: 2016-12-01 02:22:06.597700Z] Running read-buildbot-config step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: read_buildbot_config 18:22:06 INFO - Using buildbot properties: 18:22:06 INFO - { 18:22:06 INFO - "project": "", 18:22:06 INFO - "product": "firefox", 18:22:06 INFO - "script_repo_revision": "default", 18:22:06 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 18:22:06 INFO - "repository": "", 18:22:06 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 18:22:06 INFO - "buildid": "20161130170644", 18:22:06 INFO - "pgo_build": "False", 18:22:06 INFO - "basedir": "/builds/slave/test", 18:22:06 INFO - "buildnumber": 27, 18:22:06 INFO - "slavename": "talos-linux64-ix-037", 18:22:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:22:06 INFO - "platform": "ubuntu64_hw", 18:22:06 INFO - "scheduler": "tests-graphics-linux64-talos", 18:22:06 INFO - "branch": "graphics", 18:22:06 INFO - "repo_path": "projects/graphics", 18:22:06 INFO - "revision": "0792dcc4e9a6798f13963b6a23e85497f1fe90f2", 18:22:06 INFO - "stage_platform": "linux64", 18:22:06 INFO - "builduid": "37c9028061264b7cbe6277421475b483", 18:22:06 INFO - "slavebuilddir": "test" 18:22:06 INFO - } 18:22:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:22:06 INFO - Running post-action listener: _resource_record_post_action 18:22:06 INFO - [mozharness: 2016-12-01 02:22:06.600118Z] Finished read-buildbot-config step (success) 18:22:06 INFO - [mozharness: 2016-12-01 02:22:06.600207Z] Running download-and-extract step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: download_and_extract 18:22:06 INFO - mkdir: /builds/slave/test/build/tests 18:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:06 INFO - trying https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:22:07 INFO - Downloaded 1361 bytes. 18:22:07 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:22:07 INFO - Using the following test package requirements: 18:22:07 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:22:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:22:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'jsshell-linux-x86_64.zip'], 18:22:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:22:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:22:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:22:07 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:22:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:22:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:22:07 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:22:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:22:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:22:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:07 INFO - Fetch https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:22:07 INFO - Http code: 200 18:22:07 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 18:22:07 INFO - x-amz-cf-id: arI5qM0hpChJs6kSfDQVuBCeo7ziW9imUH2gcPDMGa98zoXzAltciA== 18:22:07 INFO - x-amz-version-id: R5P0B3qfSZLFLOGRkBVWeYqBhbc4qJnL 18:22:07 INFO - Expected file size: 20217576 18:22:07 INFO - Obtained file size: 20217576 18:22:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:22:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:22:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:22:11 INFO - Fetch https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:22:12 INFO - Http code: 200 18:22:12 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 18:22:12 INFO - x-amz-cf-id: Nc5tjV1pcyMdvbZyyvlyiBIcQ0p4U_vHSnO_VceMiFLJae69krTDgg== 18:22:12 INFO - x-amz-version-id: R5P0B3qfSZLFLOGRkBVWeYqBhbc4qJnL 18:22:12 INFO - Expected file size: 20217576 18:22:12 INFO - Obtained file size: 20217576 18:22:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:22:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:22:17 INFO - Fetch https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:22:17 INFO - Http code: 200 18:22:17 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 18:22:17 INFO - x-amz-cf-id: HKOY3TXlYOazXh-butWL9YwpGmBZaMfr4EOXe9n0qAKa_95Yl0d3jg== 18:22:17 INFO - x-amz-version-id: C90fAPKVXcCAoHsU6SjRuGrFzErn2RMx 18:22:17 INFO - Expected file size: 14409702 18:22:17 INFO - Obtained file size: 14409702 18:22:18 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')]} 18:22:18 INFO - trying https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:22:18 INFO - Downloading https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:22:19 INFO - Downloaded 55680840 bytes. 18:22:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:22:19 INFO - mkdir: /builds/slave/test/properties 18:22:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:19 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:19 INFO - Contents: 18:22:19 INFO - build_url:https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:22:20 INFO - Running post-action listener: _resource_record_post_action 18:22:20 INFO - Running post-action listener: set_extra_try_arguments 18:22:20 INFO - [mozharness: 2016-12-01 02:22:20.187531Z] Finished download-and-extract step (success) 18:22:20 INFO - [mozharness: 2016-12-01 02:22:20.187756Z] Running populate-webroot step. 18:22:20 INFO - Running pre-action listener: _resource_record_pre_action 18:22:20 INFO - Running main action method: populate_webroot 18:22:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:20 INFO - '--tppagecycles', 18:22:20 INFO - '7'], 18:22:20 INFO - 'tests': ['tsvgm']}}, 18:22:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['damp', 'tps']}, 18:22:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'tests': ['damp', 'tps']}, 18:22:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['dromaeo_dom']}, 18:22:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['basic_compositor_video']}, 18:22:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:22:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['a11yr', 18:22:20 INFO - 'ts_paint', 18:22:20 INFO - 'tpaint', 18:22:20 INFO - 'sessionrestore', 18:22:20 INFO - 'sessionrestore_no_auto_restore', 18:22:20 INFO - 'tabpaint']}, 18:22:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['tsvgx', 18:22:20 INFO - 'tsvgr_opacity', 18:22:20 INFO - 'tart', 18:22:20 INFO - 'tscrollx', 18:22:20 INFO - 'cart', 18:22:20 INFO - 'tsvg_static']}, 18:22:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:20 INFO - 'tsvgr_opacity', 18:22:20 INFO - 'tart', 18:22:20 INFO - 'tscrollx', 18:22:20 INFO - 'cart', 18:22:20 INFO - 'tsvg_static']}, 18:22:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'talos_options': ['--disable-e10s'], 18:22:20 INFO - 'tests': ['tp5o']}, 18:22:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'talos_options': ['--disable-e10s', 18:22:20 INFO - '--xperf_path', 18:22:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:20 INFO - 'tests': ['tp5n']}, 18:22:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:20 INFO - 'talos_options': ['--xperf_path', 18:22:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:20 INFO - 'tests': ['tp5n']}}, 18:22:20 INFO - 'talos.zip': {'path': '', 18:22:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:20 INFO - Running post-action listener: _resource_record_post_action 18:22:20 INFO - [mozharness: 2016-12-01 02:22:20.204074Z] Finished populate-webroot step (success) 18:22:20 INFO - [mozharness: 2016-12-01 02:22:20.204161Z] Running create-virtualenv step. 18:22:20 INFO - Running pre-action listener: _resource_record_pre_action 18:22:20 INFO - Running main action method: create_virtualenv 18:22:20 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:20 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 18:22:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:20 INFO - Using real prefix '/usr' 18:22:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:20 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:22 INFO - Installing pip.................done. 18:22:22 INFO - Return code: 0 18:22:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:22 INFO - Reading from file tmpfile_stdout 18:22:22 INFO - Output received: 18:22:22 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) 18:22:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:22 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:22 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 18:22:22 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 18:22:22 INFO - Using env: {'DISPLAY': ':0', 18:22:22 INFO - 'HOME': '/home/cltbld', 18:22:22 INFO - 'LANG': 'en_US.UTF-8', 18:22:22 INFO - 'LANGUAGE': 'en_US:en', 18:22:22 INFO - 'LOGNAME': 'cltbld', 18:22:22 INFO - 'MAIL': '/var/mail/cltbld', 18:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:22 INFO - 'MOZ_NO_REMOTE': '1', 18:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:22 INFO - 'NO_EM_RESTART': '1', 18:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:22 INFO - 'PWD': '/builds/slave/test', 18:22:22 INFO - 'SHELL': '/bin/bash', 18:22:22 INFO - 'SHLVL': '1', 18:22:22 INFO - 'TERM': 'linux', 18:22:22 INFO - 'TMOUT': '86400', 18:22:23 INFO - 'USER': 'cltbld', 18:22:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:23 INFO - '_': '/tools/buildbot/bin/python'} 18:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:23 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 18:22:23 INFO - Cleaning up... 18:22:23 INFO - Return code: 0 18:22:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:23 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')]} 18:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:23 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')]} 18:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:23 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:23 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 18:22:23 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 18:22:23 INFO - Using env: {'DISPLAY': ':0', 18:22:23 INFO - 'HOME': '/home/cltbld', 18:22:23 INFO - 'LANG': 'en_US.UTF-8', 18:22:23 INFO - 'LANGUAGE': 'en_US:en', 18:22:23 INFO - 'LOGNAME': 'cltbld', 18:22:23 INFO - 'MAIL': '/var/mail/cltbld', 18:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:23 INFO - 'MOZ_NO_REMOTE': '1', 18:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:23 INFO - 'NO_EM_RESTART': '1', 18:22:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:23 INFO - 'PWD': '/builds/slave/test', 18:22:23 INFO - 'SHELL': '/bin/bash', 18:22:23 INFO - 'SHLVL': '1', 18:22:23 INFO - 'TERM': 'linux', 18:22:23 INFO - 'TMOUT': '86400', 18:22:23 INFO - 'USER': 'cltbld', 18:22:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:23 INFO - '_': '/tools/buildbot/bin/python'} 18:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:23 INFO - Downloading/unpacking psutil>=3.1.1 18:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:25 INFO - Installing collected packages: psutil 18:22:25 INFO - Running setup.py install for psutil 18:22:25 INFO - building 'psutil._psutil_linux' extension 18:22:25 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 18:22:25 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 18:22:25 INFO - building 'psutil._psutil_posix' extension 18:22:25 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 18:22:25 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 18:22:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:25 INFO - Successfully installed psutil 18:22:25 INFO - Cleaning up... 18:22:26 INFO - Return code: 0 18:22:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:22:26 INFO - Using env: {'DISPLAY': ':0', 18:22:26 INFO - 'HOME': '/home/cltbld', 18:22:26 INFO - 'LANG': 'en_US.UTF-8', 18:22:26 INFO - 'LANGUAGE': 'en_US:en', 18:22:26 INFO - 'LOGNAME': 'cltbld', 18:22:26 INFO - 'MAIL': '/var/mail/cltbld', 18:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:26 INFO - 'MOZ_NO_REMOTE': '1', 18:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:26 INFO - 'NO_EM_RESTART': '1', 18:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:26 INFO - 'PWD': '/builds/slave/test', 18:22:26 INFO - 'SHELL': '/bin/bash', 18:22:26 INFO - 'SHLVL': '1', 18:22:26 INFO - 'TERM': 'linux', 18:22:26 INFO - 'TMOUT': '86400', 18:22:26 INFO - 'USER': 'cltbld', 18:22:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:26 INFO - '_': '/tools/buildbot/bin/python'} 18:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:26 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 18:22:26 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 18:22:26 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 18:22:26 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 18:22:26 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 18:22:26 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 18:22:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:28 INFO - Installing collected packages: mozsystemmonitor 18:22:28 INFO - Running setup.py install for mozsystemmonitor 18:22:28 INFO - Successfully installed mozsystemmonitor 18:22:28 INFO - Cleaning up... 18:22:28 INFO - Return code: 0 18:22:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:28 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')]} 18:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:28 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')]} 18:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:28 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:28 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 18:22:28 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 18:22:28 INFO - Using env: {'DISPLAY': ':0', 18:22:28 INFO - 'HOME': '/home/cltbld', 18:22:28 INFO - 'LANG': 'en_US.UTF-8', 18:22:28 INFO - 'LANGUAGE': 'en_US:en', 18:22:28 INFO - 'LOGNAME': 'cltbld', 18:22:28 INFO - 'MAIL': '/var/mail/cltbld', 18:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:28 INFO - 'MOZ_NO_REMOTE': '1', 18:22:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:28 INFO - 'NO_EM_RESTART': '1', 18:22:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:28 INFO - 'PWD': '/builds/slave/test', 18:22:28 INFO - 'SHELL': '/bin/bash', 18:22:28 INFO - 'SHLVL': '1', 18:22:28 INFO - 'TERM': 'linux', 18:22:28 INFO - 'TMOUT': '86400', 18:22:28 INFO - 'USER': 'cltbld', 18:22:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:28 INFO - '_': '/tools/buildbot/bin/python'} 18:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:28 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:28 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 18:22:28 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 18:22:28 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 18:22:28 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 18:22:28 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 18:22:28 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 18:22:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:30 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 18:22:30 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 18:22:30 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 18:22:30 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 18:22:30 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 18:22:30 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 18:22:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:30 INFO - warning: no files found matching '*.txt' 18:22:30 INFO - no previously-included directories found matching 'build' 18:22:30 INFO - no previously-included directories found matching 'dist' 18:22:30 INFO - no previously-included directories found matching '.git*' 18:22:30 INFO - Installing collected packages: jsonschema, functools32 18:22:30 INFO - Running setup.py install for functools32 18:22:31 INFO - warning: no files found matching '*.txt' 18:22:31 INFO - no previously-included directories found matching 'build' 18:22:31 INFO - no previously-included directories found matching 'dist' 18:22:31 INFO - no previously-included directories found matching '.git*' 18:22:31 INFO - Successfully installed jsonschema functools32 18:22:31 INFO - Cleaning up... 18:22:31 INFO - Return code: 0 18:22:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:31 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')]} 18:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:31 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')]} 18:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:31 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:31 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 18:22:31 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 18:22:31 INFO - Using env: {'DISPLAY': ':0', 18:22:31 INFO - 'HOME': '/home/cltbld', 18:22:31 INFO - 'LANG': 'en_US.UTF-8', 18:22:31 INFO - 'LANGUAGE': 'en_US:en', 18:22:31 INFO - 'LOGNAME': 'cltbld', 18:22:31 INFO - 'MAIL': '/var/mail/cltbld', 18:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:31 INFO - 'MOZ_NO_REMOTE': '1', 18:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:31 INFO - 'NO_EM_RESTART': '1', 18:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:31 INFO - 'PWD': '/builds/slave/test', 18:22:31 INFO - 'SHELL': '/bin/bash', 18:22:31 INFO - 'SHLVL': '1', 18:22:31 INFO - 'TERM': 'linux', 18:22:31 INFO - 'TMOUT': '86400', 18:22:31 INFO - 'USER': 'cltbld', 18:22:31 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:31 INFO - '_': '/tools/buildbot/bin/python'} 18:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:31 INFO - Cleaning up... 18:22:31 INFO - Return code: 0 18:22:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:31 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')]} 18:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:31 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')]} 18:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:31 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:31 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 18:22:31 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 18:22:31 INFO - Using env: {'DISPLAY': ':0', 18:22:31 INFO - 'HOME': '/home/cltbld', 18:22:31 INFO - 'LANG': 'en_US.UTF-8', 18:22:31 INFO - 'LANGUAGE': 'en_US:en', 18:22:31 INFO - 'LOGNAME': 'cltbld', 18:22:31 INFO - 'MAIL': '/var/mail/cltbld', 18:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:31 INFO - 'MOZ_NO_REMOTE': '1', 18:22:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:31 INFO - 'NO_EM_RESTART': '1', 18:22:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:31 INFO - 'PWD': '/builds/slave/test', 18:22:31 INFO - 'SHELL': '/bin/bash', 18:22:31 INFO - 'SHLVL': '1', 18:22:31 INFO - 'TERM': 'linux', 18:22:31 INFO - 'TMOUT': '86400', 18:22:31 INFO - 'USER': 'cltbld', 18:22:31 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:31 INFO - '_': '/tools/buildbot/bin/python'} 18:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:31 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:31 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 18:22:31 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 18:22:31 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 18:22:31 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 18:22:31 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 18:22:31 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 18:22:33 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:33 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 18:22:33 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 18:22:33 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 18:22:33 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 18:22:33 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 18:22:33 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 18:22:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:34 INFO - Downloading docopt-0.6.1.tar.gz 18:22:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:34 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:34 INFO - Running setup.py install for blobuploader 18:22:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:34 INFO - Running setup.py install for requests 18:22:34 INFO - Running setup.py install for docopt 18:22:34 INFO - Successfully installed blobuploader requests docopt 18:22:34 INFO - Cleaning up... 18:22:34 INFO - Return code: 0 18:22:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:34 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:34 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 18:22:34 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 18:22:34 INFO - Using env: {'DISPLAY': ':0', 18:22:34 INFO - 'HOME': '/home/cltbld', 18:22:34 INFO - 'LANG': 'en_US.UTF-8', 18:22:34 INFO - 'LANGUAGE': 'en_US:en', 18:22:34 INFO - 'LOGNAME': 'cltbld', 18:22:34 INFO - 'MAIL': '/var/mail/cltbld', 18:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:34 INFO - 'MOZ_NO_REMOTE': '1', 18:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:34 INFO - 'NO_EM_RESTART': '1', 18:22:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:34 INFO - 'PWD': '/builds/slave/test', 18:22:34 INFO - 'SHELL': '/bin/bash', 18:22:34 INFO - 'SHLVL': '1', 18:22:34 INFO - 'TERM': 'linux', 18:22:34 INFO - 'TMOUT': '86400', 18:22:34 INFO - 'USER': 'cltbld', 18:22:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:34 INFO - '_': '/tools/buildbot/bin/python'} 18:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:35 INFO - Running setup.py (path:/tmp/pip-qVZfEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:35 INFO - Running setup.py (path:/tmp/pip-hUy0EW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:35 INFO - Running setup.py (path:/tmp/pip-TKu737-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:35 INFO - Running setup.py (path:/tmp/pip-JxinDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:35 INFO - Running setup.py (path:/tmp/pip-ijcdwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:35 INFO - Running setup.py (path:/tmp/pip-uuNwAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:35 INFO - Running setup.py (path:/tmp/pip-pbkM39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:35 INFO - Running setup.py (path:/tmp/pip-pbhVSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:35 INFO - Running setup.py (path:/tmp/pip-hm7fOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:35 INFO - Running setup.py (path:/tmp/pip-BWCHz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:35 INFO - Running setup.py (path:/tmp/pip-BMKewz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:35 INFO - Running setup.py (path:/tmp/pip-Z0i6fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:36 INFO - Running setup.py (path:/tmp/pip-tRuDMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:36 INFO - Running setup.py (path:/tmp/pip-dVeA_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:36 INFO - Running setup.py (path:/tmp/pip-oyqOMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:36 INFO - Running setup.py (path:/tmp/pip-Z_QN3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:36 INFO - Running setup.py (path:/tmp/pip-6UBPkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:36 INFO - Running setup.py install for manifestparser 18:22:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:36 INFO - Running setup.py install for mozcrash 18:22:36 INFO - Running setup.py install for mozdebug 18:22:36 INFO - Running setup.py install for mozdevice 18:22:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:22:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:36 INFO - Running setup.py install for mozfile 18:22:36 INFO - Running setup.py install for mozhttpd 18:22:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozinfo 18:22:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozInstall 18:22:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozleak 18:22:37 INFO - Running setup.py install for mozlog 18:22:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for moznetwork 18:22:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozprocess 18:22:37 INFO - Running setup.py install for mozprofile 18:22:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozrunner 18:22:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:37 INFO - Running setup.py install for mozscreenshot 18:22:37 INFO - Running setup.py install for moztest 18:22:38 INFO - Running setup.py install for mozversion 18:22:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:38 INFO - Cleaning up... 18:22:38 INFO - Return code: 0 18:22:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:38 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')]} 18:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:38 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')]} 18:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:38 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:38 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 18:22:38 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 18:22:38 INFO - Using env: {'DISPLAY': ':0', 18:22:38 INFO - 'HOME': '/home/cltbld', 18:22:38 INFO - 'LANG': 'en_US.UTF-8', 18:22:38 INFO - 'LANGUAGE': 'en_US:en', 18:22:38 INFO - 'LOGNAME': 'cltbld', 18:22:38 INFO - 'MAIL': '/var/mail/cltbld', 18:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:38 INFO - 'MOZ_NO_REMOTE': '1', 18:22:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:38 INFO - 'NO_EM_RESTART': '1', 18:22:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:38 INFO - 'PWD': '/builds/slave/test', 18:22:38 INFO - 'SHELL': '/bin/bash', 18:22:38 INFO - 'SHLVL': '1', 18:22:38 INFO - 'TERM': 'linux', 18:22:38 INFO - 'TMOUT': '86400', 18:22:38 INFO - 'USER': 'cltbld', 18:22:38 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:38 INFO - '_': '/tools/buildbot/bin/python'} 18:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:38 INFO - Running setup.py (path:/tmp/pip-bqOxHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:38 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:38 INFO - Running setup.py (path:/tmp/pip-_obfpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:38 INFO - Running setup.py (path:/tmp/pip-G_mC7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:38 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:38 INFO - Running setup.py (path:/tmp/pip-RnO6JJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:38 INFO - Running setup.py (path:/tmp/pip-uccA5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:38 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:38 INFO - Running setup.py (path:/tmp/pip-UB3m1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:38 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:38 INFO - Running setup.py (path:/tmp/pip-mgG_Yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:38 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)) 18:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:38 INFO - Running setup.py (path:/tmp/pip-pbgz_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:39 INFO - Running setup.py (path:/tmp/pip-vyoaZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:39 INFO - Running setup.py (path:/tmp/pip-qwD86r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:39 INFO - Running setup.py (path:/tmp/pip-GFiXbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:39 INFO - Running setup.py (path:/tmp/pip-I_bubV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:39 INFO - Running setup.py (path:/tmp/pip-Q0Zmlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:39 INFO - Running setup.py (path:/tmp/pip-HkSLoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:39 INFO - Running setup.py (path:/tmp/pip-KFKM8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:39 INFO - Running setup.py (path:/tmp/pip-LrQTHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:39 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)) 18:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:39 INFO - Running setup.py (path:/tmp/pip-WjA4l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:39 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)) 18:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:22:39 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)) 18:22:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:39 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:22:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:41 INFO - Downloading blessings-1.6.tar.gz 18:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:41 INFO - Installing collected packages: blessings 18:22:41 INFO - Running setup.py install for blessings 18:22:41 INFO - Successfully installed blessings 18:22:41 INFO - Cleaning up... 18:22:41 INFO - Return code: 0 18:22:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:42 INFO - Reading from file tmpfile_stdout 18:22:42 INFO - Current package versions: 18:22:42 INFO - argparse == 1.2.1 18:22:42 INFO - blessings == 1.6 18:22:42 INFO - blobuploader == 1.2.4 18:22:42 INFO - docopt == 0.6.1 18:22:42 INFO - functools32 == 3.2.3-2 18:22:42 INFO - jsonschema == 2.5.1 18:22:42 INFO - manifestparser == 1.1 18:22:42 INFO - mozInstall == 1.12 18:22:42 INFO - mozcrash == 1.0 18:22:42 INFO - mozdebug == 0.1 18:22:42 INFO - mozdevice == 0.49 18:22:42 INFO - mozfile == 1.2 18:22:42 INFO - mozhttpd == 0.7 18:22:42 INFO - mozinfo == 0.9 18:22:42 INFO - mozleak == 0.1 18:22:42 INFO - mozlog == 3.4 18:22:42 INFO - moznetwork == 0.27 18:22:42 INFO - mozprocess == 0.23 18:22:42 INFO - mozprofile == 0.28 18:22:42 INFO - mozrunner == 6.13 18:22:42 INFO - mozscreenshot == 0.1 18:22:42 INFO - mozsystemmonitor == 0.3 18:22:42 INFO - moztest == 0.7 18:22:42 INFO - mozversion == 1.4 18:22:42 INFO - psutil == 3.1.1 18:22:42 INFO - requests == 1.2.3 18:22:42 INFO - wsgiref == 0.1.2 18:22:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 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 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:42 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 18:22:42 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 18:22:42 INFO - Using env: {'DISPLAY': ':0', 18:22:42 INFO - 'HOME': '/home/cltbld', 18:22:42 INFO - 'LANG': 'en_US.UTF-8', 18:22:42 INFO - 'LANGUAGE': 'en_US:en', 18:22:42 INFO - 'LOGNAME': 'cltbld', 18:22:42 INFO - 'MAIL': '/var/mail/cltbld', 18:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:42 INFO - 'MOZ_NO_REMOTE': '1', 18:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:42 INFO - 'NO_EM_RESTART': '1', 18:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:42 INFO - 'PWD': '/builds/slave/test', 18:22:42 INFO - 'SHELL': '/bin/bash', 18:22:42 INFO - 'SHLVL': '1', 18:22:42 INFO - 'TERM': 'linux', 18:22:42 INFO - 'TMOUT': '86400', 18:22:42 INFO - 'USER': 'cltbld', 18:22:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:42 INFO - '_': '/tools/buildbot/bin/python'} 18:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 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)) 18:22:42 INFO - Cleaning up... 18:22:42 INFO - Return code: 0 18:22:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7e4a85e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bbb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1657130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', '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 18:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:22:42 INFO - Using env: {'DISPLAY': ':0', 18:22:42 INFO - 'HOME': '/home/cltbld', 18:22:42 INFO - 'LANG': 'en_US.UTF-8', 18:22:42 INFO - 'LANGUAGE': 'en_US:en', 18:22:42 INFO - 'LOGNAME': 'cltbld', 18:22:42 INFO - 'MAIL': '/var/mail/cltbld', 18:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:42 INFO - 'MOZ_NO_REMOTE': '1', 18:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:42 INFO - 'NO_EM_RESTART': '1', 18:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:42 INFO - 'PWD': '/builds/slave/test', 18:22:42 INFO - 'SHELL': '/bin/bash', 18:22:42 INFO - 'SHLVL': '1', 18:22:42 INFO - 'TERM': 'linux', 18:22:42 INFO - 'TMOUT': '86400', 18:22:42 INFO - 'USER': 'cltbld', 18:22:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:42 INFO - '_': '/tools/buildbot/bin/python'} 18:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:42 INFO - Cleaning up... 18:22:42 INFO - Return code: 0 18:22:42 INFO - Running post-action listener: _resource_record_post_action 18:22:42 INFO - Running post-action listener: _start_resource_monitoring 18:22:42 INFO - Starting resource monitoring. 18:22:42 INFO - [mozharness: 2016-12-01 02:22:42.614683Z] Finished create-virtualenv step (success) 18:22:42 INFO - [mozharness: 2016-12-01 02:22:42.615162Z] Running install step. 18:22:42 INFO - Running pre-action listener: _resource_record_pre_action 18:22:42 INFO - Running main action method: install 18:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:42 INFO - Reading from file tmpfile_stdout 18:22:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:42 INFO - Reading from file tmpfile_stdout 18:22:42 INFO - Output received: 18:22:42 INFO - Usage: mozinstall [options] installer 18:22:42 INFO - Options: 18:22:42 INFO - -h, --help show this help message and exit 18:22:42 INFO - -d DEST, --destination=DEST 18:22:42 INFO - Directory to install application into. [default: 18:22:42 INFO - "/builds/slave/test"] 18:22:42 INFO - --app=APP Application being installed. [default: firefox] 18:22:42 INFO - mkdir: /builds/slave/test/build/application 18:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:22:58 INFO - Reading from file tmpfile_stdout 18:22:58 INFO - Output received: 18:22:58 INFO - /builds/slave/test/build/application/firefox/firefox 18:22:58 INFO - Running post-action listener: _resource_record_post_action 18:22:58 INFO - [mozharness: 2016-12-01 02:22:58.954784Z] Finished install step (success) 18:22:58 INFO - [mozharness: 2016-12-01 02:22:58.955024Z] Running run-tests step. 18:22:58 INFO - Running pre-action listener: _resource_record_pre_action 18:22:58 INFO - Running main action method: run_tests 18:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:22:58 INFO - Python 2.7.3 18:22:58 INFO - Return code: 0 18:22:58 INFO - grabbing minidump binary from tooltool 18:22: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')]} 18:22:58 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 0x16e5fc0>, '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 0x16e6ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16e7350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:22:58 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 18:22:58 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 18:22:58 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 18:22:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:22:59 INFO - Return code: 0 18:22:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:22:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:22:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:22:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:22:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:22:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 18:22:59 INFO - Using env: {'DISPLAY': ':0', 18:22:59 INFO - 'HOME': '/home/cltbld', 18:22:59 INFO - 'LANG': 'en_US.UTF-8', 18:22:59 INFO - 'LANGUAGE': 'en_US:en', 18:22:59 INFO - 'LOGNAME': 'cltbld', 18:22:59 INFO - 'MAIL': '/var/mail/cltbld', 18:22:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:22:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:59 INFO - 'MOZ_NO_REMOTE': '1', 18:22:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:22:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:59 INFO - 'NO_EM_RESTART': '1', 18:22:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:59 INFO - 'PWD': '/builds/slave/test', 18:22:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:22:59 INFO - 'SHELL': '/bin/bash', 18:22:59 INFO - 'SHLVL': '1', 18:22:59 INFO - 'TERM': 'linux', 18:22:59 INFO - 'TMOUT': '86400', 18:22:59 INFO - 'USER': 'cltbld', 18:22:59 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122', 18:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:59 INFO - '_': '/tools/buildbot/bin/python'} 18:22:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:22:59 INFO - mozversion application_buildid: 20161130170644 18:22:59 INFO - mozversion application_changeset: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 18:22:59 INFO - mozversion application_display_name: Nightly 18:22:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:22:59 INFO - mozversion application_name: Firefox 18:22:59 INFO - mozversion application_remotingname: firefox 18:22:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 18:22:59 INFO - mozversion application_vendor: Mozilla 18:22:59 INFO - mozversion application_version: 53.0a1 18:22:59 INFO - mozversion platform_buildid: 20161130170644 18:22:59 INFO - mozversion platform_changeset: 0792dcc4e9a6798f13963b6a23e85497f1fe90f2 18:22:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 18:22:59 INFO - mozversion platform_version: 53.0a1 18:22:59 INFO - using testdate: 1480558979 18:22:59 INFO - actual date: 1480558979 18:22:59 INFO - starting webserver on 'localhost:47928' 18:22:59 INFO - SUITE-START | Running 2 tests 18:22:59 INFO - TEST-START | dromaeo_css 18:22:59 INFO - operating with platform_type : linux_ 18:22:59 INFO - Initialising browser for dromaeo_css test... 18:22:59 INFO - TEST-INFO | started process 3489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm_zt7m/profile http://localhost:47928/getInfo.html) 18:23:03 INFO - PROCESS | 3489 | __metrics Screen width/height:1600/1200 18:23:03 INFO - PROCESS | 3489 | colorDepth:24 18:23:03 INFO - PROCESS | 3489 | Browser inner width/height: 1024/697 18:23:03 INFO - PROCESS | 3489 | __metrics 18:23:04 INFO - PROCESS | 3489 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:23:04 INFO - PROCESS | 3489 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:23:04 INFO - PROCESS | 3489 | console.error: PushService: 18:23:04 INFO - PROCESS | 3489 | stateChangeProcessEnqueue: Error transitioning state 18:23:04 INFO - PROCESS | 3489 | UnknownError 18:23:05 INFO - TEST-INFO | 3489: exit 0 18:23:05 INFO - Browser initialized. 18:23:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:05 INFO - TEST-INFO | started process 3571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm_zt7m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:17 INFO - PROCESS | 3571 | RSS: Main: 175296512 18:23:17 INFO - PROCESS | 3571 | 18:24:08 INFO - PROCESS | 3571 | 1480559048654 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] 18:26:21 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47928/tests/dromaeo/cssquery-ext.html) 18:26:24 INFO - PROCESS | 3571 | RSS: Main: 384827392 18:26:24 INFO - PROCESS | 3571 | 18:29:27 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-ext.html (next: http://localhost:47928/tests/dromaeo/cssquery-jquery.html) 18:29:28 INFO - PROCESS | 3571 | RSS: Main: 283856896 18:29:28 INFO - PROCESS | 3571 | 18:32:30 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47928/tests/dromaeo/cssquery-mootools.html) 18:32:30 INFO - PROCESS | 3571 | RSS: Main: 223010816 18:32:30 INFO - PROCESS | 3571 | 18:35:33 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47928/tests/dromaeo/cssquery-prototype.html) 18:35:33 INFO - PROCESS | 3571 | RSS: Main: 225984512 18:35:33 INFO - PROCESS | 3571 | 18:38:37 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47928/tests/dromaeo/cssquery-yui.html) 18:38:37 INFO - PROCESS | 3571 | RSS: Main: 225869824 18:38:37 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:47928/tests/dromaeo/cssquery-yui.html (next: http://localhost:47928/tests/dromaeo/cssquery-dojo.html) 18:41:40 INFO - PROCESS | 3571 | RSS: Main: 317685760 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | __start_tp_report 18:41:40 INFO - PROCESS | 3571 | _x_x_mozilla_page_load 18:41:40 INFO - PROCESS | 3571 | _x_x_mozilla_page_load_details 18:41:40 INFO - PROCESS | 3571 | |i|pagename|runs| 18:41:40 INFO - PROCESS | 3571 | |0;dojo.html;2587.5471792436324;2605.9478810423802;2612.7256638053004;2633.2758491414857;2648.205538338498;4565.041341318319;4581.656375771822;4585.358049873021;4660.021395506939;4661.160991021619;4631.837885674002;4642.5821676049145;4647.953520464803;4675.906481870361;4722.338872557853;4216.683748718843;4219.620234178923;4256;4286.507051689049;4389.297712366022;4210.179015092058;4283.485981682203;4284.550122237167;4331;4362.956370436286;4160.791960401968;4195.4336164618;4292.377605247253;4353.869383918487;4382.0797632497215;4216.2832318505625;4225.619694227535;4226.239276930182;4229.450171477719;4229.70392072555;4200.957990420087;4209.5579964103945;4209.705320627558;4213.136307057046;4218.430511880888;4601.171789077965;4608.723476591415;4615.515370886076;4620.353150558924;4656.743879086649;4948.925766113512;4990;4996.4254110777365;5003.574696150831;5035.320231768737;4831.251156070814;5017.799288028475;5018.197088465828;5021.874453138665;5077.898442031139;4597.816087356468;4610.861674149781;4634.328022436728;4639.164950308909;4641.350210970467;5045.571126454219;5093.898122037538;5095.159298715745;5095.847124586268;5137.33214682089;4853.223484242575;4897.926531102036;4898.510148985073;4899.436564795023;4933.728644924492;4813.3983252093485;4842.080004799077;4845.515448455197;4915.803367865316;5024.949750502521;4890.070886531619;4926.8768280793265;4930.11257973561;4957.256411538226;4976.90046199074;4952.727599981963;4953.628477864103;4958.9008219835405;4964.1809101498575;4986.850394488171;4157.376393540993;4180.832766689298;4274.551172126944;4276.87169384919;4302.612764851178;3686.281175170816;3709.3137769512728;3711.814409279525;3722.925541489155;3763.962360376361;4275.080857615565;4283.87148385549;4284.421603083606;4284.76433796138;4329.6969212154845;4958.479359667255;4964.8758781030765;4965.702057876539;4965.875853103629;5008.048470790538;4189.287821070426;4192.45498085247;4195.223883581486;4231.682623803227;4231.724937879028;4455.910881782346;4458.130664520388;4465.843695470643;4498.932516012263;4505.639548836085;4609.262517997105;4613.746243956481;4616.28447590624;4618.284165954148;4663;4820.566149046603;4894;4906.435759887516;4923.704577725348;4924.359833221658;4653.906921861611;4666.043461090536;4667.253239481666;4670.1827180244;4677.134730074947;4895.559399654048;4897.020595880909;4899;4901.264810278487;4903.754812259444;4964.131277026579;4965.950340496549;4970.055689418999;4970.130227210296;4978.4274808397495;4802.975985120052;4837.395325584302;4845.370101886732;4847.491013443538;4848.224284114524;4807.543283388072;4824.517548245148;4826.300186473012;4902.411710594752;4906.582940450102;4793.448753393265;4796.688215265996;4796.9760151199025;4798.7360695160705;4841.540053694894;4866.197077482176;4877;4895.314655948241;4895.8041678332465;4924.359833221658;4626.838060667995;4650.255959046536;4667.346571480063;4717.103750287434;4767.427908650984 18:41:40 INFO - PROCESS | 3571 | |1;ext.html;532.8055259830162;554.5452728762414;559.8796258804356;563.4168635462297;563.5435297409099;37514.81242593787;38706.22587548251;38841.22317553647;42211.57788422115;42241;38729.806350968276;39082.21835563287;39164.412533812014;39172;39197;7296.890546641805;7499.250074992485;7556.622168891561;7707.653155607983;7711.845763084735;9624.855627165598;9961.153301969342;9970.15253703436;10237.948810255939;10250.179985601171;9887.159591434736;9933.056359645821;9938.304318697657;9944.055314745088;9962.701118966443;9752.90247097527;9777.31558790885;9796.314258001978;9802.36284641496;9809.068138526829;9803.705888823346;9834;9855.063768941938;9928.900710992943;9933.950330248375;37663.8116809417;38089.23821523554;38096.61903380986;38101.61898381036;38106.23787524234;3162.794418362822;3185.984070079635;3546.9113272168142;3581.7492775505725;3610.422332426799;27194.5920811188;27801.304967375774;27970.440591188064;28030.299242518897;28052.298692532644;3444.1389652586854;3478.9446703463586;3624.746267761258;3691.0956815580084;3693.833777479993;245812;246187;246430.5890940358;247525;248222;49965.75017124891;50160;50176.247356289685;50297.24554131691;50321;52235;52313.738431307596;52326;52359.21461178085;52361;13296.601101966957;13430.26133562644;13432.126911750705;13436.193828370133;13437.328133593477;8797.604107815036;9087.954560227157;11005.504752286166;11230.270032447865;11329.603463878724;17493.562660933323;18351;18361.08194590265;18406.907965460356;18446;6663;7012.158540975116;7023.508354415142;7176.246494118147;7178.246284140195;757.818123650331;832.7585000349831;892.8571428571528;943.1628118721798;974.152487336008;26859.597106043035;26895.462090758076;27649.86175069152;27742.86128569344;27750.861245693643;818.0428898189133;835.5091383812011;874.6632546469655;875.3522393428944;1019.8777136916078;14123.505677301246;14590.124592524482;14749.77875331871;14794.260286985607;14841.554753357417;363515;366883;367305;368464;370774;47877.76061119672;48647;48649.27026094611;48663;48742.75628621905;1052.9789404211872;1106.7233191702076;1111.9888801111886;1170.9590164344206;1196.904247660185;26786.46427071409;28270.293242668275;30966.225844353172;31190.844045780534;31646.841765791945;45579.316310256036;46282.76858615685;46369;46409.071818564116;46430.30354544617;328583;330715;331520;331535;331582;35903.28193436169;37440.438393423574;37544;37586.81206593949;37806.81096594499;40250.59749402468;40278.39582406208;40336;40734;40880.79559602183;33809;34301.65698342985;34461.483077754354;34483.482747759306;34677.653223467445;33535.16162095967;34084;34248;34653;35166.82416588003;33593.4303874514;34250.314993700486;34511.82744086264;34645.826770865984;34860.82569587237;26904.865475672497;28157.310561366383;30612.846935765177;30996;31849.203769905936;24311.637218035943;24536.512012469073;24548.140815071387;24772.320560687273;24836.50326993414 18:41:40 INFO - PROCESS | 3571 | |2;jquery.html;334.44816053511715;339.9099238701743;358.2137208826626;364.6809042088172;381.9847206111756;12418;13556.593302200938;15559.922200388997;15839;16148.515544533682;16370.263338149782;16935.66128677426;17087.401940932068;17115.74326385105;17203.139843007863;4495.280755079188;4805.639577031719;5082.847514574569;5084.059449001947;5086.389633244016;5745.396733342981;5940.643561386309;5961.225040744719;5967.612105213169;5972.462478376945;5634.267545219116;5749.195112684228;5944.4352786485215;5951.196588460567;5953.017752070903;5740.253767010283;5749.511291540224;5764.13537969308;5764.798232061858;5793.797217097403;4533.705309154894;4790.329353890458;4821.373221481185;5006.224035274538;5011.223260394645;19177.712334314998;19550.413487595393;19628.50928726779;19694;19821.702674459895;1856.675081860669;1906.5996140810446;1906.9332573360005;2029.4520479470461;2054.30153747725;8592.925884264467;8931.285497160212;9272.629094836198;11171.441427928652;12659.113862029662;1609.5815088077184;1668.5244705259065;1670.2400407814416;1698.3970690404976;1760.3046796515432;66579.3342066578;67426;68497.65751171262;68498;69043.65478172628;8900.837514241724;8922.107789221025;9237.076292370844;9248;9265.536723163816;11940.701482463008;11996.940015299868;12040.39798010096;12073.45669444877;12080.21478603888;6665.266820649725;6855.897161542581;6887.621180835102;7000.92999070013;7001.614911179832;6685.431738302299;6730.9326906731285;6878.209005964278;6889.517733758589;7002.964985175042;3662.1943172502006;3725.403935370328;3791.2417516496257;4036.2129384770274;4247.235497610398;1730.287015854295;1747.9300827966738;1761.9471415857545;1777.7511148439228;1782.7504149419092;758.5865703191773;833.7540425574471;843.8101427178958;885.464294102059;939.5396255834592;15138.848611513744;15986.520404387888;16076.67846643084;16108.436204732778;16950.491485255214;795.188907314536;850.3282406898481;879.3844308983737;882.2897567458258;905.7011186308503;8832.116788321246;8935.865962010575;8998.190162885372;9503.382280151767;10073.798524029626;294012;297927;298436;406956;415099;47994.76002620035;55545.444545554026;57361;57948;61484.692576537724;880.2429910277157;887.1040249348076;927.5037854747677;927.7819712367453;930.1396208507103;14914.627134321729;15254.923725381303;15539.922300388425;15621.21893905323;15856.44502442409;28790.71209287964;31951.36097277995;35369.46945795764;35674.46488302729;36162.638373615926;45051.09897802091;46901.76549117233;50047;50074.49925500698;52246.47753522416;7415.5179913305465;7416.66625001887;7502.474826762293;7646.502977306457;7652.546016256893;8454.281386082252;10926.672199834018;11794.29234245948;12121.45453454579;12212.267263964191;6376.139221205078;9269.490178040272;10104.393736375841;10680.198985075995;11122.277051991594;10448.32085914413;10520.158387328842;10618.415987120783;10862.891371086504;10893.945530272298;10262.743431414274;10644.84032739525;10886.67339979802;10901.073408760345;10911.127109831035;7571.848563028819;7590.354819840375;7696.345810605937;7773.1060927994;7776.300132988058;9440.433573985583;10735.624253151102;10835.241533092923;10913.290636108628;11041.668749937515 18:41:40 INFO - PROCESS | 3571 | |3;mootools.html;1330.102181027806;1350.0954360578414;1422.7367936931669;1650.1419261983776;1659.5104444188971;6087.634741915477;6319.210098737658;6498;6890.896636550456;6894.172699276088;6433.324500927405;6705.1953765548205;6705.765298214564;6820.454363650909;6825.453963682906;1983.9702404463944;2019.0207749241606;2019.485031317012;2021.7877122902105;2022.1405902491456;1729.9567510812267;1789.677857985562;1943.650142974264;2002.6995950607452;2011.6781314989605;1756.6135450200936;1775.0237369446818;1954.5797653504494;1992.750906136733;2010.5576773109892;1889.527618095476;1890.1966664167674;1892.924283028677;1893.4887580353366;1895.6208758248406;1920.1455352368062;1928.9035548222532;2006.809353111452;2008.397480755782;2010.7486564179478;6741.966290168567;6890.37986581205;6892.104026476576;6892.483063770187;6912.758053468155;4019.256437559061;4136.627703506669;4142.357934520154;4146.108586653853;4153.460050193486;2436;2585.7931365490717;2631.6184153297663;2702.5270577648835;2708.133397312861;844.4764246167415;874.8469017921839;882.1266945723687;891.5453118909338;892.8437523433438;9006.189442950166;9090.63637454501;9191.31065170115;9197.724068277896;9209.953950230272;3601.3697602919383;3709.350863598859;3751.0997360633796;3781.8865434037025;3789.7157713171623;7480.738174163878;7506.549607023596;7508.699652013858;7531.924680753123;7536.434767392468;4074.714769966074;4081.5102187737066;4086.1214838809797;4086.141910198832;4091.1204091120553;3700.796456194881;3703.814809259526;3755.1550901047594;3763.5295588051495;3764.4541541476333;3192.968070319267;3255.3977514159956;3306.851191696331;3337.132345590164;3343.314620502791;1100.658795773313;1110.9833352499718;1115.7378016166194;1115.760111576015;1127.5884302229626;583.3699604427209;738.8079099434186;803.61426515273;805.6898094233762;825.6037102190982;4668.7665616719705;4856.635752318591;4908.558229759267;5167.638265321391;5213.635045546851;805.7139715401065;817.852786498436;824.1017291152673;829.995850020746;868.2142660891903;3366.5118557808983;3380.8309584521166;3473.3747925373677;3508.368493671164;3612.6387361263664;7580.696772129053;7950.761477155695;7954.363650907913;7957.283844454027;7962.840743185104;7753.922460775433;7907.0906845712325;7946.165652606509;7950.681972721027;7953.920460795318;6314.557980941382;6458.6770661466735;6464.191976003;6464.385883341075;6464.935350646528;4772.618190544678;4880.31675565414;4951.3810773653295;4991.80032798684;5187.2478490619405;7389.519681220703;7475.887861682188;7502.437317201341;7540.057492813398;7555.244475552401;7489.70041198346;7526.435517336331;7605.277498637621;7639.541627502367;7762.728304509315;5792.160136780228;5889;5907.320658124198;5916.822495325147;5916.822495325147;6205.348438413902;6238.6880655966115;6250.406211409909;6253.155823963707;6318.652474113968;5814.825555233349;5822.534197264124;5833.766649333979;5882.1470886722045;6077.3011103721865;5674.886502270014;5857.94142058591;5871.559633027626;5917.763289468373;5924.585279030599;5565.638233514808;5572.554195664418;5613.045782216951;5627.803026894039;5677.489025987681;4216.262154122955;4347.108842687178;4372.4315838942;4395.318725597537;4439.955600443955;4047.1500984792347;4153.937690934699;4170.311898536829;4215.831366745419;4276.828926843016 18:41:40 INFO - PROCESS | 3571 | |4;prototype.html;261.7735658655264;275.5219693831203;279.7174853398068;288.8887778205391;289.92461959890437;4532.954670453294;4614.469336026354;4740.217864052427;4742.419473297664;4829.637777166709;4706.529347065291;4730.243161094226;4740.928886066712;4743.833965811197;4761;3422.2642131941566;3667.9633203668022;3682.650148235913;3688.274842409173;3700.7594506357136;3646.4165733482646;3661.176235347039;3661.2311414602964;3668.532586965222;3670.522832031832;3607.7474576779637;3627.3289441453417;3633.4731463937715;3634;3671.724620653462;3482.3980968754404;3660;3661.2677464507206;3662.926741465182;3667.889963301105;3203.0550987458755;3235.9290054365224;3255.20247539352;3259.4784834426614;3261.1031966209484;4574;4634.2353511670535;4635.235186194274;4642.605378542828;4643.326717625925;4205.11692544566;4222.619964203236;4225.3873188387815;4225.598568136023;4239.385289133089;4774.450938142123;4899.363082799213;4906.141425250603;4958.9752051239875;4989.525995030466;3571;3574.428091505347;3579.0157706630885;3585.175409655794;3596.820158992066;5158.9226161607985;5162.535371816517;5166.21765490905;5181.559567436772;5192.454792246797;3428.211511352403;3645.5989841117457;3655.4334078217917;3661.761985470936;3667.8716244931297;3645.9453108203397;3663.9740872555726;3667.468217108504;3670.853165873335;3673.9265214695556;3994.520657521116;4076.5935752165456;4185.665146788249;4189.895252618709;4194.140201258735;4778.808847646055;4909.656324057353;4940.530649588283;4944.75276236194;4965.528274813887;1242.428482897859;1254.6486983644595;1255.7676829786335;1258.9118761686593;1265.7531781302557;819.8975128108987;876.2201640539924;882.2104216725994;885.8804061451622;885.8848349714495;4225.408442818008;4237.5762423758;4242.915141697149;4268.957310426856;4272.87181384559;4929.8941666749715;4944.132403206132;4953.356063711694;4965.702057876467;4995.350604421402;4268.146370725867;4277.636400905958;4295.291276939332;4316.913661726748;4324.567543245713;2528.6965564132056;2634.855082970455;2650.204938518437;2663.4539919316494;2664.200739778059;4642.767861606906;4654.720716757005;4702.623790096784;4717.466823282447;4721.291806229093;5107.9489205107475;5118.795248190105;5119.6160287978555;5130.51260130287;5140;4611.976940115278;4883.267509873547;4909.705417674951;4918.458969513351;4919.631027672939;5178.97410512945;5185.403678577012;5189.247559103985;5191.8182863599595;5200.60995425337;4916.2312556819825;5106.285120083117;5108.080545501697;5132.640715149903;5137.794488220429;4820.927686084781;4836.806527738851;4837.250226214942;4849.393825771778;4851.490593487633;3542.4509201073874;3571.7320351274925;3585.103724068983;3591.6654174785554;3594.388953877795;4953.554180123806;4981.252812078217;5172.913688125535;5182.97408512955;5186.662866913639;4907.607391408607;4949.430815456125;4971.01159536174;4994.902141100673;5002.774875130701;4962.652614316963;4987.700737955734;5001.4998500149695;5008.524190201925;5017.448080711046;4802.27965805132;4901.705897646153;4925.359703238556;4932.482089380564;4941;5001.449840517467;5005.972581104658;5022.824201152942;5026.4973502649445;5050.267711181931;4930.778114984762;4933;4938.08645400602;4953.777080031336;4958.925616115834 18:41:40 INFO - PROCESS | 3571 | |5;yui.html;635.1235295292496;645.2740666749906;652.4323838260714;672.5594735448283;747.3460721868365;12679.366031698424;13934.51229206978;13967.580972570839;13987.580372588813;14017;13383.197008179515;13598.592042238724;13603.251821149834;13612.72774544511;13618.34442283755;617.1729881958175;634.7968650031992;745.3478206569251;767.5624893810531;782.6086956521739;771.1247733822096;782.6908371193375;784.3372350363962;784.4548039112819;784.901887264092;820.216693058131;821.0270829067545;822.1737154160061;822.9588520573977;823.2919689067397;645.5223134880155;669.0031852539729;779.185750890321;807.7415227127321;809.6073404398862;586.1003359842664;590.5128269177945;590.7695998560565;591.139891457932;591.452906061891;13957.371918263703;13972.650683732989;13976.93011534946;14024.08843425174;14079.296035198198;12242.44908979098;12814.551490697873;13101.737965240736;13151.60545183646;13196.14225075367;11930.642080737593;12078.637640870787;12227.205231660002;12239.69400764979;12259.203151795105;3211.2614098757417;3234.3369609230053;3336.566246387954;3429.639887811794;4157.08544120293;65687.3431265686;66055.3394466054;67129.3287067128;67337.66331168362;67890.66054669744;10097.141742951932;10261.127804136731;10307.94846025765;10331.638392656221;10494.790104197873;18082.819171808114;18109.818901810813;18152.273909043754;18316.908415457838;18394.356197533023;6510.9023364649565;6538.313477084933;6701.463882889358;6726.058351830748;6953.89569156463;3714.2200137971327;3846.6345697158727;3866.709996750255;3880.2239552089695;3892.2215556888737;2507.7868381187805;2589.533883900878;2601.531724289646;2606.6350710900488;2638.366791969952;461.1169610196503;462.99074018520116;467.01692936369136;468.08722990169304;472.775431669954;565.8839937812738;567.9744411501492;569.8517487263168;610.5207412181505;624.3506752976854;7677.232276772278;7799.844003120019;8221.465604735673;8272.710455134042;8304.875426868603;592.2448877680991;598.332858862374;600.4145957691303;617.8887800196007;621.4624349937362;3878.83634909526;3990.3817491702757;3997.560268370477;4066.3900414937402;4110.917781644351;67516;69665;70254.64872675604;71177.64411177911;71902.64048679723;45400.54599454029;48087;54198.72900635472;61200.38799611947;61861.38138618556;42871.57128428676;49652.75173624109;51030.234546481835;51566;52052.47947520628;7881;7924.366050715814;8300.294474969694;8396.328293736364;8455.719621427414;34178.48732268968;34271.14322141966;35167.82416088005;35882.8205858969;35984.64015359813;45116.774416129025;49269;49300.50699493103;50159;50498.74750626223;10840.403777792297;10860.619878304222;11117.722056948642;11122.944385278022;11353.54585816558;11982.400879955792;12279.69300767488;12416.503339866304;12450.937745311216;13857.307134643026;11555.537778489102;11788.76422471563;12062.638120856389;12096.395180240777;12172.391380430767;11156.442177891235;11616.535338586362;11798.410079496163;11940.223885447418;11962.282263063897;10651.733706657396;10657.786844263226;10733.302335348177;12597.992160626945;12622;10170.74573135648;10383.221258405802;10442.738931526468;10480.63317783874;10694.144468442662;9030.909690903007;9817.950910245403;10097.141742951637;10190;10206.387616742722 18:41:40 INFO - PROCESS | 3571 | __end_tp_report 18:41:40 INFO - PROCESS | 3571 | __start_cc_report 18:41:40 INFO - PROCESS | 3571 | _x_x_mozilla_cycle_collect,2514 18:41:40 INFO - PROCESS | 3571 | __end_cc_report 18:41:40 INFO - PROCESS | 3571 | __startTimestamp1480560100833__endTimestamp 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | ------- Summary: start ------- 18:41:40 INFO - PROCESS | 3571 | Number of tests: 6 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#0] dojo.html Cycles:165 Average:4582.35 Median:4667.25 stddev:475.67 (10.2%) stddev-sans-first:450.65 18:41:40 INFO - PROCESS | 3571 | Values: 2587.5 2605.9 2612.7 2633.3 2648.2 4565.0 4581.7 4585.4 4660.0 4661.2 4631.8 4642.6 4648.0 4675.9 4722.3 4216.7 4219.6 4256.0 4286.5 4389.3 4210.2 4283.5 4284.6 4331.0 4363.0 4160.8 4195.4 4292.4 4353.9 4382.1 4216.3 4225.6 4226.2 4229.5 4229.7 4201.0 4209.6 4209.7 4213.1 4218.4 4601.2 4608.7 4615.5 4620.4 4656.7 4948.9 4990.0 4996.4 5003.6 5035.3 4831.3 5017.8 5018.2 5021.9 5077.9 4597.8 4610.9 4634.3 4639.2 4641.4 5045.6 5093.9 5095.2 5095.8 5137.3 4853.2 4897.9 4898.5 4899.4 4933.7 4813.4 4842.1 4845.5 4915.8 5024.9 4890.1 4926.9 4930.1 4957.3 4976.9 4952.7 4953.6 4958.9 4964.2 4986.9 4157.4 4180.8 4274.6 4276.9 4302.6 3686.3 3709.3 3711.8 3722.9 3764.0 4275.1 4283.9 4284.4 4284.8 4329.7 4958.5 4964.9 4965.7 4965.9 5008.0 4189.3 4192.5 4195.2 4231.7 4231.7 4455.9 4458.1 4465.8 4498.9 4505.6 4609.3 4613.7 4616.3 4618.3 4663.0 4820.6 4894.0 4906.4 4923.7 4924.4 4653.9 4666.0 4667.3 4670.2 4677.1 4895.6 4897.0 4899.0 4901.3 4903.8 4964.1 4966.0 4970.1 4970.1 4978.4 4803.0 4837.4 4845.4 4847.5 4848.2 4807.5 4824.5 4826.3 4902.4 4906.6 4793.4 4796.7 4797.0 4798.7 4841.5 4866.2 4877.0 4895.3 4895.8 4924.4 4626.8 4650.3 4667.3 4717.1 4767.4 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#1] ext.html Cycles:180 Average:47234.67 Median:187233.92 stddev:83758.52 (44.7%) stddev-sans-first:83920.08 18:41:40 INFO - PROCESS | 3571 | Values: 532.8 554.5 559.9 563.4 563.5 37514.8 38706.2 38841.2 42211.6 42241.0 38729.8 39082.2 39164.4 39172.0 39197.0 7296.9 7499.3 7556.6 7707.7 7711.8 9624.9 9961.2 9970.2 10237.9 10250.2 9887.2 9933.1 9938.3 9944.1 9962.7 9752.9 9777.3 9796.3 9802.4 9809.1 9803.7 9834.0 9855.1 9928.9 9934.0 37663.8 38089.2 38096.6 38101.6 38106.2 3162.8 3186.0 3546.9 3581.7 3610.4 27194.6 27801.3 27970.4 28030.3 28052.3 3444.1 3478.9 3624.7 3691.1 3693.8 245812.0 246187.0 246430.6 247525.0 248222.0 49965.8 50160.0 50176.2 50297.2 50321.0 52235.0 52313.7 52326.0 52359.2 52361.0 13296.6 13430.3 13432.1 13436.2 13437.3 8797.6 9088.0 11005.5 11230.3 11329.6 17493.6 18351.0 18361.1 18406.9 18446.0 6663.0 7012.2 7023.5 7176.2 7178.2 757.8 832.8 892.9 943.2 974.2 26859.6 26895.5 27649.9 27742.9 27750.9 818.0 835.5 874.7 875.4 1019.9 14123.5 14590.1 14749.8 14794.3 14841.6 363515.0 366883.0 367305.0 368464.0 370774.0 47877.8 48647.0 48649.3 48663.0 48742.8 1053.0 1106.7 1112.0 1171.0 1196.9 26786.5 28270.3 30966.2 31190.8 31646.8 45579.3 46282.8 46369.0 46409.1 46430.3 328583.0 330715.0 331520.0 331535.0 331582.0 35903.3 37440.4 37544.0 37586.8 37806.8 40250.6 40278.4 40336.0 40734.0 40880.8 33809.0 34301.7 34461.5 34483.5 34677.7 33535.2 34084.0 34248.0 34653.0 35166.8 33593.4 34250.3 34511.8 34645.8 34860.8 26904.9 28157.3 30612.8 30996.0 31849.2 24311.6 24536.5 24548.1 24772.3 24836.5 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#2] jquery.html Cycles:180 Average:22036.70 Median:24792.40 stddev:57174.24 (230.6%) stddev-sans-first:57311.41 18:41:40 INFO - PROCESS | 3571 | Values: 334.4 339.9 358.2 364.7 382.0 12418.0 13556.6 15559.9 15839.0 16148.5 16370.3 16935.7 17087.4 17115.7 17203.1 4495.3 4805.6 5082.8 5084.1 5086.4 5745.4 5940.6 5961.2 5967.6 5972.5 5634.3 5749.2 5944.4 5951.2 5953.0 5740.3 5749.5 5764.1 5764.8 5793.8 4533.7 4790.3 4821.4 5006.2 5011.2 19177.7 19550.4 19628.5 19694.0 19821.7 1856.7 1906.6 1906.9 2029.5 2054.3 8592.9 8931.3 9272.6 11171.4 12659.1 1609.6 1668.5 1670.2 1698.4 1760.3 66579.3 67426.0 68497.7 68498.0 69043.7 8900.8 8922.1 9237.1 9248.0 9265.5 11940.7 11996.9 12040.4 12073.5 12080.2 6665.3 6855.9 6887.6 7000.9 7001.6 6685.4 6730.9 6878.2 6889.5 7003.0 3662.2 3725.4 3791.2 4036.2 4247.2 1730.3 1747.9 1761.9 1777.8 1782.8 758.6 833.8 843.8 885.5 939.5 15138.8 15986.5 16076.7 16108.4 16950.5 795.2 850.3 879.4 882.3 905.7 8832.1 8935.9 8998.2 9503.4 10073.8 294012.0 297927.0 298436.0 406956.0 415099.0 47994.8 55545.4 57361.0 57948.0 61484.7 880.2 887.1 927.5 927.8 930.1 14914.6 15254.9 15539.9 15621.2 15856.4 28790.7 31951.4 35369.5 35674.5 36162.6 45051.1 46901.8 50047.0 50074.5 52246.5 7415.5 7416.7 7502.5 7646.5 7652.5 8454.3 10926.7 11794.3 12121.5 12212.3 6376.1 9269.5 10104.4 10680.2 11122.3 10448.3 10520.2 10618.4 10862.9 10893.9 10262.7 10644.8 10886.7 10901.1 10911.1 7571.8 7590.4 7696.3 7773.1 7776.3 9440.4 10735.6 10835.2 10913.3 11041.7 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#3] mootools.html Cycles:180 Average:4493.44 Median:5569.10 stddev:2381.99 (42.8%) stddev-sans-first:2376.81 18:41:40 INFO - PROCESS | 3571 | Values: 1330.1 1350.1 1422.7 1650.1 1659.5 6087.6 6319.2 6498.0 6890.9 6894.2 6433.3 6705.2 6705.8 6820.5 6825.5 1984.0 2019.0 2019.5 2021.8 2022.1 1730.0 1789.7 1943.7 2002.7 2011.7 1756.6 1775.0 1954.6 1992.8 2010.6 1889.5 1890.2 1892.9 1893.5 1895.6 1920.1 1928.9 2006.8 2008.4 2010.7 6742.0 6890.4 6892.1 6892.5 6912.8 4019.3 4136.6 4142.4 4146.1 4153.5 2436.0 2585.8 2631.6 2702.5 2708.1 844.5 874.8 882.1 891.5 892.8 9006.2 9090.6 9191.3 9197.7 9210.0 3601.4 3709.4 3751.1 3781.9 3789.7 7480.7 7506.5 7508.7 7531.9 7536.4 4074.7 4081.5 4086.1 4086.1 4091.1 3700.8 3703.8 3755.2 3763.5 3764.5 3193.0 3255.4 3306.9 3337.1 3343.3 1100.7 1111.0 1115.7 1115.8 1127.6 583.4 738.8 803.6 805.7 825.6 4668.8 4856.6 4908.6 5167.6 5213.6 805.7 817.9 824.1 830.0 868.2 3366.5 3380.8 3473.4 3508.4 3612.6 7580.7 7950.8 7954.4 7957.3 7962.8 7753.9 7907.1 7946.2 7950.7 7953.9 6314.6 6458.7 6464.2 6464.4 6464.9 4772.6 4880.3 4951.4 4991.8 5187.2 7389.5 7475.9 7502.4 7540.1 7555.2 7489.7 7526.4 7605.3 7639.5 7762.7 5792.2 5889.0 5907.3 5916.8 5916.8 6205.3 6238.7 6250.4 6253.2 6318.7 5814.8 5822.5 5833.8 5882.1 6077.3 5674.9 5857.9 5871.6 5917.8 5924.6 5565.6 5572.6 5613.0 5627.8 5677.5 4216.3 4347.1 4372.4 4395.3 4440.0 4047.2 4153.9 4170.3 4215.8 4276.8 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#4] prototype.html Cycles:180 Average:4106.98 Median:4704.58 stddev:1200.86 (25.5%) stddev-sans-first:1169.03 18:41:40 INFO - PROCESS | 3571 | Values: 261.8 275.5 279.7 288.9 289.9 4533.0 4614.5 4740.2 4742.4 4829.6 4706.5 4730.2 4740.9 4743.8 4761.0 3422.3 3668.0 3682.7 3688.3 3700.8 3646.4 3661.2 3661.2 3668.5 3670.5 3607.7 3627.3 3633.5 3634.0 3671.7 3482.4 3660.0 3661.3 3662.9 3667.9 3203.1 3235.9 3255.2 3259.5 3261.1 4574.0 4634.2 4635.2 4642.6 4643.3 4205.1 4222.6 4225.4 4225.6 4239.4 4774.5 4899.4 4906.1 4959.0 4989.5 3571.0 3574.4 3579.0 3585.2 3596.8 5158.9 5162.5 5166.2 5181.6 5192.5 3428.2 3645.6 3655.4 3661.8 3667.9 3645.9 3664.0 3667.5 3670.9 3673.9 3994.5 4076.6 4185.7 4189.9 4194.1 4778.8 4909.7 4940.5 4944.8 4965.5 1242.4 1254.6 1255.8 1258.9 1265.8 819.9 876.2 882.2 885.9 885.9 4225.4 4237.6 4242.9 4269.0 4272.9 4929.9 4944.1 4953.4 4965.7 4995.4 4268.1 4277.6 4295.3 4316.9 4324.6 2528.7 2634.9 2650.2 2663.5 2664.2 4642.8 4654.7 4702.6 4717.5 4721.3 5107.9 5118.8 5119.6 5130.5 5140.0 4612.0 4883.3 4909.7 4918.5 4919.6 5179.0 5185.4 5189.2 5191.8 5200.6 4916.2 5106.3 5108.1 5132.6 5137.8 4820.9 4836.8 4837.3 4849.4 4851.5 3542.5 3571.7 3585.1 3591.7 3594.4 4953.6 4981.3 5172.9 5183.0 5186.7 4907.6 4949.4 4971.0 4994.9 5002.8 4962.7 4987.7 5001.5 5008.5 5017.4 4802.3 4901.7 4925.4 4932.5 4941.0 5001.4 5006.0 5022.8 5026.5 5050.3 4930.8 4933.0 4938.1 4953.8 4958.9 18:41:40 INFO - PROCESS | 3571 | 18:41:40 INFO - PROCESS | 3571 | [#5] yui.html Cycles:180 Average:15032.66 Median:4088.65 stddev:18874.41 (461.6%) stddev-sans-first:18896.39 18:41:40 INFO - PROCESS | 3571 | Values: 635.1 645.3 652.4 672.6 747.3 12679.4 13934.5 13967.6 13987.6 14017.0 13383.2 13598.6 13603.3 13612.7 13618.3 617.2 634.8 745.3 767.6 782.6 771.1 782.7 784.3 784.5 784.9 820.2 821.0 822.2 823.0 823.3 645.5 669.0 779.2 807.7 809.6 586.1 590.5 590.8 591.1 591.5 13957.4 13972.7 13976.9 14024.1 14079.3 12242.4 12814.6 13101.7 13151.6 13196.1 11930.6 12078.6 12227.2 12239.7 12259.2 3211.3 3234.3 3336.6 3429.6 4157.1 65687.3 66055.3 67129.3 67337.7 67890.7 10097.1 10261.1 10307.9 10331.6 10494.8 18082.8 18109.8 18152.3 18316.9 18394.4 6510.9 6538.3 6701.5 6726.1 6953.9 3714.2 3846.6 3866.7 3880.2 3892.2 2507.8 2589.5 2601.5 2606.6 2638.4 461.1 463.0 467.0 468.1 472.8 565.9 568.0 569.9 610.5 624.4 7677.2 7799.8 8221.5 8272.7 8304.9 592.2 598.3 600.4 617.9 621.5 3878.8 3990.4 3997.6 4066.4 4110.9 67516.0 69665.0 70254.6 71177.6 71902.6 45400.5 48087.0 54198.7 61200.4 61861.4 42871.6 49652.8 51030.2 51566.0 52052.5 7881.0 7924.4 8300.3 8396.3 8455.7 34178.5 34271.1 35167.8 35882.8 35984.6 45116.8 49269.0 49300.5 50159.0 50498.7 10840.4 10860.6 11117.7 11122.9 11353.5 11982.4 12279.7 12416.5 12450.9 13857.3 11555.5 11788.8 12062.6 12096.4 12172.4 11156.4 11616.5 11798.4 11940.2 11962.3 10651.7 10657.8 10733.3 12598.0 12622.0 10170.7 10383.2 10442.7 10480.6 10694.1 9030.9 9818.0 10097.1 10190.0 10206.4 18:41:40 INFO - PROCESS | 3571 | -------- Summary: end -------- 18:41:40 INFO - PROCESS | 3571 | 18:41:41 INFO - TEST-INFO | 3571: exit 0 18:41:41 INFO - TEST-OK | dromaeo_css | took 1122699ms 18:41:41 INFO - TEST-START | kraken 18:41:41 INFO - operating with platform_type : linux_ 18:41:41 INFO - Initialising browser for kraken test... 18:41:41 INFO - TEST-INFO | started process 3875 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLODNfU/profile http://localhost:47928/getInfo.html) 18:41:46 INFO - PROCESS | 3875 | __metrics Screen width/height:1600/1200 18:41:46 INFO - PROCESS | 3875 | colorDepth:24 18:41:46 INFO - PROCESS | 3875 | Browser inner width/height: 1024/697 18:41:46 INFO - PROCESS | 3875 | __metrics 18:41:46 INFO - PROCESS | 3875 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:41:46 INFO - PROCESS | 3875 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:47 INFO - PROCESS | 3875 | console.error: PushService: 18:41:47 INFO - PROCESS | 3875 | stateChangeProcessEnqueue: Error transitioning state 18:41:47 INFO - PROCESS | 3875 | UnknownError 18:41:47 INFO - TEST-INFO | 3875: exit 0 18:41:47 INFO - Browser initialized. 18:41:47 INFO - Running cycle 1/1 for kraken test... 18:41:47 INFO - TEST-INFO | started process 3955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLODNfU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:00 INFO - PROCESS | 3955 | RSS: Main: 174559232 18:42:00 INFO - PROCESS | 3955 | 18:42:03 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47928/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:03 INFO - PROCESS | 3955 | RSS: Main: 239185920 18:42:03 INFO - PROCESS | 3955 | 18:42:06 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47928/tests/kraken/driver.html?testName=audio-dft) 18:42:07 INFO - PROCESS | 3955 | RSS: Main: 218316800 18:42:07 INFO - PROCESS | 3955 | 18:42:10 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47928/tests/kraken/driver.html?testName=audio-fft) 18:42:11 INFO - PROCESS | 3955 | RSS: Main: 300744704 18:42:11 INFO - PROCESS | 3955 | 18:42:13 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47928/tests/kraken/driver.html?testName=audio-oscillator) 18:42:13 INFO - PROCESS | 3955 | RSS: Main: 211738624 18:42:13 INFO - PROCESS | 3955 | 18:42:16 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47928/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:16 INFO - PROCESS | 3955 | RSS: Main: 214835200 18:42:16 INFO - PROCESS | 3955 | 18:42:19 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47928/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:20 INFO - PROCESS | 3955 | RSS: Main: 254324736 18:42:20 INFO - PROCESS | 3955 | 18:42:24 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47928/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:24 INFO - PROCESS | 3955 | RSS: Main: 247963648 18:42:24 INFO - PROCESS | 3955 | 18:42:27 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47928/tests/kraken/driver.html?testName=json-parse-financial) 18:42:28 INFO - PROCESS | 3955 | RSS: Main: 250179584 18:42:28 INFO - PROCESS | 3955 | 18:42:29 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47928/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:30 INFO - PROCESS | 3955 | RSS: Main: 213712896 18:42:30 INFO - PROCESS | 3955 | 18:42:32 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:32 INFO - PROCESS | 3955 | RSS: Main: 221724672 18:42:32 INFO - PROCESS | 3955 | 18:42:35 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:35 INFO - PROCESS | 3955 | RSS: Main: 229617664 18:42:35 INFO - PROCESS | 3955 | 18:42:38 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:39 INFO - PROCESS | 3955 | RSS: Main: 238227456 18:42:39 INFO - PROCESS | 3955 | 18:42:42 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:43 INFO - PROCESS | 3955 | RSS: Main: 234905600 18:42:43 INFO - PROCESS | 3955 | 18:42:44 INFO - PROCESS | 3955 | Cycle 1(1): loaded http://localhost:47928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47928/tests/kraken/driver.html?testName=ai-astar) 18:42:45 INFO - PROCESS | 3955 | RSS: Main: 285048832 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | __start_tp_report 18:42:45 INFO - PROCESS | 3955 | _x_x_mozilla_page_load 18:42:45 INFO - PROCESS | 3955 | _x_x_mozilla_page_load_details 18:42:45 INFO - PROCESS | 3955 | |i|pagename|runs| 18:42:45 INFO - PROCESS | 3955 | |0;ai-astar;114;119;117;118;116;117;119;119;116;115 18:42:45 INFO - PROCESS | 3955 | |1;audio-beat-detection;159;133;137;171;130;130;133;132;161;130 18:42:45 INFO - PROCESS | 3955 | |2;audio-dft;160;160;160;162;161;161;162;161;161;160 18:42:45 INFO - PROCESS | 3955 | |3;audio-fft;78;76;76;104;77;80;78;78;93;77 18:42:45 INFO - PROCESS | 3955 | |4;audio-oscillator;85;86;117;84;84;83;103;83;84;108 18:42:45 INFO - PROCESS | 3955 | |5;imaging-gaussian-blur;118;117;117;118;117;117;117;117;118;117 18:42:45 INFO - PROCESS | 3955 | |6;imaging-darkroom;146;146;147;148;147;147;147;147;147;147 18:42:45 INFO - PROCESS | 3955 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;96;96 18:42:45 INFO - PROCESS | 3955 | |8;json-parse-financial;64;62;63;64;64;66;64;64;64;65 18:42:45 INFO - PROCESS | 3955 | |9;json-stringify-tinderbox;66;65;66;66;65;65;66;66;66;66 18:42:45 INFO - PROCESS | 3955 | |10;stanford-crypto-aes;86;87;95;92;88;91;92;85;85;92 18:42:45 INFO - PROCESS | 3955 | |11;stanford-crypto-ccm;149;155;151;153;142;140;139;143;182;144 18:42:45 INFO - PROCESS | 3955 | |12;stanford-crypto-pbkdf2;179;178;178;179;178;179;178;176;176;178 18:42:45 INFO - PROCESS | 3955 | |13;stanford-crypto-sha256-iterative;72;72;70;70;73;73;71;71;70;70 18:42:45 INFO - PROCESS | 3955 | __end_tp_report 18:42:45 INFO - PROCESS | 3955 | __start_cc_report 18:42:45 INFO - PROCESS | 3955 | _x_x_mozilla_cycle_collect,1985 18:42:45 INFO - PROCESS | 3955 | __end_cc_report 18:42:45 INFO - PROCESS | 3955 | __startTimestamp1480560165217__endTimestamp 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | ------- Summary: start ------- 18:42:45 INFO - PROCESS | 3955 | Number of tests: 14 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.50 stddev:1.76 (1.5%) stddev-sans-first:1.50 18:42:45 INFO - PROCESS | 3955 | Values: 114.0 119.0 117.0 118.0 116.0 117.0 119.0 119.0 116.0 115.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:135.00 stddev:15.66 (11.6%) stddev-sans-first:15.30 18:42:45 INFO - PROCESS | 3955 | Values: 159.0 133.0 137.0 171.0 130.0 130.0 133.0 132.0 161.0 130.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 18:42:45 INFO - PROCESS | 3955 | Values: 160.0 160.0 160.0 162.0 161.0 161.0 162.0 161.0 161.0 160.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#3] audio-fft Cycles:10 Average:81.70 Median:78.00 stddev:9.30 (11.9%) stddev-sans-first:9.77 18:42:45 INFO - PROCESS | 3955 | Values: 78.0 76.0 76.0 104.0 77.0 80.0 78.0 78.0 93.0 77.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:84.00 stddev:12.65 (15.1%) stddev-sans-first:13.18 18:42:45 INFO - PROCESS | 3955 | Values: 85.0 86.0 117.0 84.0 84.0 83.0 103.0 83.0 84.0 108.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:42:45 INFO - PROCESS | 3955 | Values: 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 18:42:45 INFO - PROCESS | 3955 | Values: 146.0 146.0 147.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 18:42:45 INFO - PROCESS | 3955 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:1.05 (1.6%) stddev-sans-first:1.12 18:42:45 INFO - PROCESS | 3955 | Values: 64.0 62.0 63.0 64.0 64.0 66.0 64.0 64.0 64.0 65.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 18:42:45 INFO - PROCESS | 3955 | Values: 66.0 65.0 66.0 66.0 65.0 65.0 66.0 66.0 66.0 66.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:91.50 stddev:3.53 (3.9%) stddev-sans-first:3.54 18:42:45 INFO - PROCESS | 3955 | Values: 86.0 87.0 95.0 92.0 88.0 91.0 92.0 85.0 85.0 92.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:150.00 stddev:12.60 (8.4%) stddev-sans-first:13.36 18:42:45 INFO - PROCESS | 3955 | Values: 149.0 155.0 151.0 153.0 142.0 140.0 139.0 143.0 182.0 144.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:178.00 stddev:1.10 (0.6%) stddev-sans-first:1.09 18:42:45 INFO - PROCESS | 3955 | Values: 179.0 178.0 178.0 179.0 178.0 179.0 178.0 176.0 176.0 178.0 18:42:45 INFO - PROCESS | 3955 | 18:42:45 INFO - PROCESS | 3955 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:71.50 stddev:1.23 (1.7%) stddev-sans-first:1.27 18:42:45 INFO - PROCESS | 3955 | Values: 72.0 72.0 70.0 70.0 73.0 73.0 71.0 71.0 70.0 70.0 18:42:45 INFO - PROCESS | 3955 | -------- Summary: end -------- 18:42:45 INFO - PROCESS | 3955 | 18:42:46 INFO - TEST-INFO | 3955: exit 0 18:42:46 INFO - TEST-OK | kraken | took 64715ms 18:42:46 INFO - SUITE-END | took 1187s 18:42:46 INFO - Completed test suite (00:19:47) 18:42:46 INFO - javascript benchmark 18:42:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2587.5471792436324, 2605.9478810423802, 2612.7256638053004, 2633.2758491414857, 2648.205538338498, 4565.041341318319, 4581.656375771822, 4585.358049873021, 4660.021395506939, 4661.160991021619, 4631.837885674002, 4642.5821676049145, 4647.953520464803, 4675.906481870361, 4722.338872557853, 4216.683748718843, 4219.620234178923, 4256.0, 4286.507051689049, 4389.297712366022, 4210.179015092058, 4283.485981682203, 4284.550122237167, 4331.0, 4362.956370436286, 4160.791960401968, 4195.4336164618, 4292.377605247253, 4353.869383918487, 4382.0797632497215, 4216.2832318505625, 4225.619694227535, 4226.239276930182, 4229.450171477719, 4229.70392072555, 4200.957990420087, 4209.5579964103945, 4209.705320627558, 4213.136307057046, 4218.430511880888, 4601.171789077965, 4608.723476591415, 4615.515370886076, 4620.353150558924, 4656.743879086649, 4948.925766113512, 4990.0, 4996.4254110777365, 5003.574696150831, 5035.320231768737, 4831.251156070814, 5017.799288028475, 5018.197088465828, 5021.874453138665, 5077.898442031139, 4597.816087356468, 4610.861674149781, 4634.328022436728, 4639.164950308909, 4641.350210970467, 5045.571126454219, 5093.898122037538, 5095.159298715745, 5095.847124586268, 5137.33214682089, 4853.223484242575, 4897.926531102036, 4898.510148985073, 4899.436564795023, 4933.728644924492, 4813.3983252093485, 4842.080004799077, 4845.515448455197, 4915.803367865316, 5024.949750502521, 4890.070886531619, 4926.8768280793265, 4930.11257973561, 4957.256411538226, 4976.90046199074, 4952.727599981963, 4953.628477864103, 4958.9008219835405, 4964.1809101498575, 4986.850394488171, 4157.376393540993, 4180.832766689298, 4274.551172126944, 4276.87169384919, 4302.612764851178, 3686.281175170816, 3709.3137769512728, 3711.814409279525, 3722.925541489155, 3763.962360376361, 4275.080857615565, 4283.87148385549, 4284.421603083606, 4284.76433796138, 4329.6969212154845, 4958.479359667255, 4964.8758781030765, 4965.702057876539, 4965.875853103629, 5008.048470790538, 4189.287821070426, 4192.45498085247, 4195.223883581486, 4231.682623803227, 4231.724937879028, 4455.910881782346, 4458.130664520388, 4465.843695470643, 4498.932516012263, 4505.639548836085, 4609.262517997105, 4613.746243956481, 4616.28447590624, 4618.284165954148, 4663.0, 4820.566149046603, 4894.0, 4906.435759887516, 4923.704577725348, 4924.359833221658, 4653.906921861611, 4666.043461090536, 4667.253239481666, 4670.1827180244, 4677.134730074947, 4895.559399654048, 4897.020595880909, 4899.0, 4901.264810278487, 4903.754812259444, 4964.131277026579, 4965.950340496549, 4970.055689418999, 4970.130227210296, 4978.4274808397495, 4802.975985120052, 4837.395325584302, 4845.370101886732, 4847.491013443538, 4848.224284114524, 4807.543283388072, 4824.517548245148, 4826.300186473012, 4902.411710594752, 4906.582940450102, 4793.448753393265, 4796.688215265996, 4796.9760151199025, 4798.7360695160705, 4841.540053694894, 4866.197077482176, 4877.0, 4895.314655948241, 4895.8041678332465, 4924.359833221658, 4626.838060667995, 4650.255959046536, 4667.346571480063, 4717.103750287434, 4767.427908650984], "value": 4552.422808178023, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.8055259830162, 554.5452728762414, 559.8796258804356, 563.4168635462297, 563.5435297409099, 37514.81242593787, 38706.22587548251, 38841.22317553647, 42211.57788422115, 42241.0, 38729.806350968276, 39082.21835563287, 39164.412533812014, 39172.0, 39197.0, 7296.890546641805, 7499.250074992485, 7556.622168891561, 7707.653155607983, 7711.845763084735, 9624.855627165598, 9961.153301969342, 9970.15253703436, 10237.948810255939, 10250.179985601171, 9887.159591434736, 9933.056359645821, 9938.304318697657, 9944.055314745088, 9962.701118966443, 9752.90247097527, 9777.31558790885, 9796.314258001978, 9802.36284641496, 9809.068138526829, 9803.705888823346, 9834.0, 9855.063768941938, 9928.900710992943, 9933.950330248375, 37663.8116809417, 38089.23821523554, 38096.61903380986, 38101.61898381036, 38106.23787524234, 3162.794418362822, 3185.984070079635, 3546.9113272168142, 3581.7492775505725, 3610.422332426799, 27194.5920811188, 27801.304967375774, 27970.440591188064, 28030.299242518897, 28052.298692532644, 3444.1389652586854, 3478.9446703463586, 3624.746267761258, 3691.0956815580084, 3693.833777479993, 245812.0, 246187.0, 246430.5890940358, 247525.0, 248222.0, 49965.75017124891, 50160.0, 50176.247356289685, 50297.24554131691, 50321.0, 52235.0, 52313.738431307596, 52326.0, 52359.21461178085, 52361.0, 13296.601101966957, 13430.26133562644, 13432.126911750705, 13436.193828370133, 13437.328133593477, 8797.604107815036, 9087.954560227157, 11005.504752286166, 11230.270032447865, 11329.603463878724, 17493.562660933323, 18351.0, 18361.08194590265, 18406.907965460356, 18446.0, 6663.0, 7012.158540975116, 7023.508354415142, 7176.246494118147, 7178.246284140195, 757.818123650331, 832.7585000349831, 892.8571428571528, 943.1628118721798, 974.152487336008, 26859.597106043035, 26895.462090758076, 27649.86175069152, 27742.86128569344, 27750.861245693643, 818.0428898189133, 835.5091383812011, 874.6632546469655, 875.3522393428944, 1019.8777136916078, 14123.505677301246, 14590.124592524482, 14749.77875331871, 14794.260286985607, 14841.554753357417, 363515.0, 366883.0, 367305.0, 368464.0, 370774.0, 47877.76061119672, 48647.0, 48649.27026094611, 48663.0, 48742.75628621905, 1052.9789404211872, 1106.7233191702076, 1111.9888801111886, 1170.9590164344206, 1196.904247660185, 26786.46427071409, 28270.293242668275, 30966.225844353172, 31190.844045780534, 31646.841765791945, 45579.316310256036, 46282.76858615685, 46369.0, 46409.071818564116, 46430.30354544617, 328583.0, 330715.0, 331520.0, 331535.0, 331582.0, 35903.28193436169, 37440.438393423574, 37544.0, 37586.81206593949, 37806.81096594499, 40250.59749402468, 40278.39582406208, 40336.0, 40734.0, 40880.79559602183, 33809.0, 34301.65698342985, 34461.483077754354, 34483.482747759306, 34677.653223467445, 33535.16162095967, 34084.0, 34248.0, 34653.0, 35166.82416588003, 33593.4303874514, 34250.314993700486, 34511.82744086264, 34645.826770865984, 34860.82569587237, 26904.865475672497, 28157.310561366383, 30612.846935765177, 30996.0, 31849.203769905936, 24311.637218035943, 24536.512012469073, 24548.140815071387, 24772.320560687273, 24836.50326993414], "value": 17868.579917582818, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.44816053511715, 339.9099238701743, 358.2137208826626, 364.6809042088172, 381.9847206111756, 12418.0, 13556.593302200938, 15559.922200388997, 15839.0, 16148.515544533682, 16370.263338149782, 16935.66128677426, 17087.401940932068, 17115.74326385105, 17203.139843007863, 4495.280755079188, 4805.639577031719, 5082.847514574569, 5084.059449001947, 5086.389633244016, 5745.396733342981, 5940.643561386309, 5961.225040744719, 5967.612105213169, 5972.462478376945, 5634.267545219116, 5749.195112684228, 5944.4352786485215, 5951.196588460567, 5953.017752070903, 5740.253767010283, 5749.511291540224, 5764.13537969308, 5764.798232061858, 5793.797217097403, 4533.705309154894, 4790.329353890458, 4821.373221481185, 5006.224035274538, 5011.223260394645, 19177.712334314998, 19550.413487595393, 19628.50928726779, 19694.0, 19821.702674459895, 1856.675081860669, 1906.5996140810446, 1906.9332573360005, 2029.4520479470461, 2054.30153747725, 8592.925884264467, 8931.285497160212, 9272.629094836198, 11171.441427928652, 12659.113862029662, 1609.5815088077184, 1668.5244705259065, 1670.2400407814416, 1698.3970690404976, 1760.3046796515432, 66579.3342066578, 67426.0, 68497.65751171262, 68498.0, 69043.65478172628, 8900.837514241724, 8922.107789221025, 9237.076292370844, 9248.0, 9265.536723163816, 11940.701482463008, 11996.940015299868, 12040.39798010096, 12073.45669444877, 12080.21478603888, 6665.266820649725, 6855.897161542581, 6887.621180835102, 7000.92999070013, 7001.614911179832, 6685.431738302299, 6730.9326906731285, 6878.209005964278, 6889.517733758589, 7002.964985175042, 3662.1943172502006, 3725.403935370328, 3791.2417516496257, 4036.2129384770274, 4247.235497610398, 1730.287015854295, 1747.9300827966738, 1761.9471415857545, 1777.7511148439228, 1782.7504149419092, 758.5865703191773, 833.7540425574471, 843.8101427178958, 885.464294102059, 939.5396255834592, 15138.848611513744, 15986.520404387888, 16076.67846643084, 16108.436204732778, 16950.491485255214, 795.188907314536, 850.3282406898481, 879.3844308983737, 882.2897567458258, 905.7011186308503, 8832.116788321246, 8935.865962010575, 8998.190162885372, 9503.382280151767, 10073.798524029626, 294012.0, 297927.0, 298436.0, 406956.0, 415099.0, 47994.76002620035, 55545.444545554026, 57361.0, 57948.0, 61484.692576537724, 880.2429910277157, 887.1040249348076, 927.5037854747677, 927.7819712367453, 930.1396208507103, 14914.627134321729, 15254.923725381303, 15539.922300388425, 15621.21893905323, 15856.44502442409, 28790.71209287964, 31951.36097277995, 35369.46945795764, 35674.46488302729, 36162.638373615926, 45051.09897802091, 46901.76549117233, 50047.0, 50074.49925500698, 52246.47753522416, 7415.5179913305465, 7416.66625001887, 7502.474826762293, 7646.502977306457, 7652.546016256893, 8454.281386082252, 10926.672199834018, 11794.29234245948, 12121.45453454579, 12212.267263964191, 6376.139221205078, 9269.490178040272, 10104.393736375841, 10680.198985075995, 11122.277051991594, 10448.32085914413, 10520.158387328842, 10618.415987120783, 10862.891371086504, 10893.945530272298, 10262.743431414274, 10644.84032739525, 10886.67339979802, 10901.073408760345, 10911.127109831035, 7571.848563028819, 7590.354819840375, 7696.345810605937, 7773.1060927994, 7776.300132988058, 9440.433573985583, 10735.624253151102, 10835.241533092923, 10913.290636108628, 11041.668749937515], "value": 7929.8962772115365, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.102181027806, 1350.0954360578414, 1422.7367936931669, 1650.1419261983776, 1659.5104444188971, 6087.634741915477, 6319.210098737658, 6498.0, 6890.896636550456, 6894.172699276088, 6433.324500927405, 6705.1953765548205, 6705.765298214564, 6820.454363650909, 6825.453963682906, 1983.9702404463944, 2019.0207749241606, 2019.485031317012, 2021.7877122902105, 2022.1405902491456, 1729.9567510812267, 1789.677857985562, 1943.650142974264, 2002.6995950607452, 2011.6781314989605, 1756.6135450200936, 1775.0237369446818, 1954.5797653504494, 1992.750906136733, 2010.5576773109892, 1889.527618095476, 1890.1966664167674, 1892.924283028677, 1893.4887580353366, 1895.6208758248406, 1920.1455352368062, 1928.9035548222532, 2006.809353111452, 2008.397480755782, 2010.7486564179478, 6741.966290168567, 6890.37986581205, 6892.104026476576, 6892.483063770187, 6912.758053468155, 4019.256437559061, 4136.627703506669, 4142.357934520154, 4146.108586653853, 4153.460050193486, 2436.0, 2585.7931365490717, 2631.6184153297663, 2702.5270577648835, 2708.133397312861, 844.4764246167415, 874.8469017921839, 882.1266945723687, 891.5453118909338, 892.8437523433438, 9006.189442950166, 9090.63637454501, 9191.31065170115, 9197.724068277896, 9209.953950230272, 3601.3697602919383, 3709.350863598859, 3751.0997360633796, 3781.8865434037025, 3789.7157713171623, 7480.738174163878, 7506.549607023596, 7508.699652013858, 7531.924680753123, 7536.434767392468, 4074.714769966074, 4081.5102187737066, 4086.1214838809797, 4086.141910198832, 4091.1204091120553, 3700.796456194881, 3703.814809259526, 3755.1550901047594, 3763.5295588051495, 3764.4541541476333, 3192.968070319267, 3255.3977514159956, 3306.851191696331, 3337.132345590164, 3343.314620502791, 1100.658795773313, 1110.9833352499718, 1115.7378016166194, 1115.760111576015, 1127.5884302229626, 583.3699604427209, 738.8079099434186, 803.61426515273, 805.6898094233762, 825.6037102190982, 4668.7665616719705, 4856.635752318591, 4908.558229759267, 5167.638265321391, 5213.635045546851, 805.7139715401065, 817.852786498436, 824.1017291152673, 829.995850020746, 868.2142660891903, 3366.5118557808983, 3380.8309584521166, 3473.3747925373677, 3508.368493671164, 3612.6387361263664, 7580.696772129053, 7950.761477155695, 7954.363650907913, 7957.283844454027, 7962.840743185104, 7753.922460775433, 7907.0906845712325, 7946.165652606509, 7950.681972721027, 7953.920460795318, 6314.557980941382, 6458.6770661466735, 6464.191976003, 6464.385883341075, 6464.935350646528, 4772.618190544678, 4880.31675565414, 4951.3810773653295, 4991.80032798684, 5187.2478490619405, 7389.519681220703, 7475.887861682188, 7502.437317201341, 7540.057492813398, 7555.244475552401, 7489.70041198346, 7526.435517336331, 7605.277498637621, 7639.541627502367, 7762.728304509315, 5792.160136780228, 5889.0, 5907.320658124198, 5916.822495325147, 5916.822495325147, 6205.348438413902, 6238.6880655966115, 6250.406211409909, 6253.155823963707, 6318.652474113968, 5814.825555233349, 5822.534197264124, 5833.766649333979, 5882.1470886722045, 6077.3011103721865, 5674.886502270014, 5857.94142058591, 5871.559633027626, 5917.763289468373, 5924.585279030599, 5565.638233514808, 5572.554195664418, 5613.045782216951, 5627.803026894039, 5677.489025987681, 4216.262154122955, 4347.108842687178, 4372.4315838942, 4395.318725597537, 4439.955600443955, 4047.1500984792347, 4153.937690934699, 4170.311898536829, 4215.831366745419, 4276.828926843016], "value": 3688.993449059512, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.7735658655264, 275.5219693831203, 279.7174853398068, 288.8887778205391, 289.92461959890437, 4532.954670453294, 4614.469336026354, 4740.217864052427, 4742.419473297664, 4829.637777166709, 4706.529347065291, 4730.243161094226, 4740.928886066712, 4743.833965811197, 4761.0, 3422.2642131941566, 3667.9633203668022, 3682.650148235913, 3688.274842409173, 3700.7594506357136, 3646.4165733482646, 3661.176235347039, 3661.2311414602964, 3668.532586965222, 3670.522832031832, 3607.7474576779637, 3627.3289441453417, 3633.4731463937715, 3634.0, 3671.724620653462, 3482.3980968754404, 3660.0, 3661.2677464507206, 3662.926741465182, 3667.889963301105, 3203.0550987458755, 3235.9290054365224, 3255.20247539352, 3259.4784834426614, 3261.1031966209484, 4574.0, 4634.2353511670535, 4635.235186194274, 4642.605378542828, 4643.326717625925, 4205.11692544566, 4222.619964203236, 4225.3873188387815, 4225.598568136023, 4239.385289133089, 4774.450938142123, 4899.363082799213, 4906.141425250603, 4958.9752051239875, 4989.525995030466, 3571.0, 3574.428091505347, 3579.0157706630885, 3585.175409655794, 3596.820158992066, 5158.9226161607985, 5162.535371816517, 5166.21765490905, 5181.559567436772, 5192.454792246797, 3428.211511352403, 3645.5989841117457, 3655.4334078217917, 3661.761985470936, 3667.8716244931297, 3645.9453108203397, 3663.9740872555726, 3667.468217108504, 3670.853165873335, 3673.9265214695556, 3994.520657521116, 4076.5935752165456, 4185.665146788249, 4189.895252618709, 4194.140201258735, 4778.808847646055, 4909.656324057353, 4940.530649588283, 4944.75276236194, 4965.528274813887, 1242.428482897859, 1254.6486983644595, 1255.7676829786335, 1258.9118761686593, 1265.7531781302557, 819.8975128108987, 876.2201640539924, 882.2104216725994, 885.8804061451622, 885.8848349714495, 4225.408442818008, 4237.5762423758, 4242.915141697149, 4268.957310426856, 4272.87181384559, 4929.8941666749715, 4944.132403206132, 4953.356063711694, 4965.702057876467, 4995.350604421402, 4268.146370725867, 4277.636400905958, 4295.291276939332, 4316.913661726748, 4324.567543245713, 2528.6965564132056, 2634.855082970455, 2650.204938518437, 2663.4539919316494, 2664.200739778059, 4642.767861606906, 4654.720716757005, 4702.623790096784, 4717.466823282447, 4721.291806229093, 5107.9489205107475, 5118.795248190105, 5119.6160287978555, 5130.51260130287, 5140.0, 4611.976940115278, 4883.267509873547, 4909.705417674951, 4918.458969513351, 4919.631027672939, 5178.97410512945, 5185.403678577012, 5189.247559103985, 5191.8182863599595, 5200.60995425337, 4916.2312556819825, 5106.285120083117, 5108.080545501697, 5132.640715149903, 5137.794488220429, 4820.927686084781, 4836.806527738851, 4837.250226214942, 4849.393825771778, 4851.490593487633, 3542.4509201073874, 3571.7320351274925, 3585.103724068983, 3591.6654174785554, 3594.388953877795, 4953.554180123806, 4981.252812078217, 5172.913688125535, 5182.97408512955, 5186.662866913639, 4907.607391408607, 4949.430815456125, 4971.01159536174, 4994.902141100673, 5002.774875130701, 4962.652614316963, 4987.700737955734, 5001.4998500149695, 5008.524190201925, 5017.448080711046, 4802.27965805132, 4901.705897646153, 4925.359703238556, 4932.482089380564, 4941.0, 5001.449840517467, 5005.972581104658, 5022.824201152942, 5026.4973502649445, 5050.267711181931, 4930.778114984762, 4933.0, 4938.08645400602, 4953.777080031336, 4958.925616115834], "value": 3723.357718309777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.1235295292496, 645.2740666749906, 652.4323838260714, 672.5594735448283, 747.3460721868365, 12679.366031698424, 13934.51229206978, 13967.580972570839, 13987.580372588813, 14017.0, 13383.197008179515, 13598.592042238724, 13603.251821149834, 13612.72774544511, 13618.34442283755, 617.1729881958175, 634.7968650031992, 745.3478206569251, 767.5624893810531, 782.6086956521739, 771.1247733822096, 782.6908371193375, 784.3372350363962, 784.4548039112819, 784.901887264092, 820.216693058131, 821.0270829067545, 822.1737154160061, 822.9588520573977, 823.2919689067397, 645.5223134880155, 669.0031852539729, 779.185750890321, 807.7415227127321, 809.6073404398862, 586.1003359842664, 590.5128269177945, 590.7695998560565, 591.139891457932, 591.452906061891, 13957.371918263703, 13972.650683732989, 13976.93011534946, 14024.08843425174, 14079.296035198198, 12242.44908979098, 12814.551490697873, 13101.737965240736, 13151.60545183646, 13196.14225075367, 11930.642080737593, 12078.637640870787, 12227.205231660002, 12239.69400764979, 12259.203151795105, 3211.2614098757417, 3234.3369609230053, 3336.566246387954, 3429.639887811794, 4157.08544120293, 65687.3431265686, 66055.3394466054, 67129.3287067128, 67337.66331168362, 67890.66054669744, 10097.141742951932, 10261.127804136731, 10307.94846025765, 10331.638392656221, 10494.790104197873, 18082.819171808114, 18109.818901810813, 18152.273909043754, 18316.908415457838, 18394.356197533023, 6510.9023364649565, 6538.313477084933, 6701.463882889358, 6726.058351830748, 6953.89569156463, 3714.2200137971327, 3846.6345697158727, 3866.709996750255, 3880.2239552089695, 3892.2215556888737, 2507.7868381187805, 2589.533883900878, 2601.531724289646, 2606.6350710900488, 2638.366791969952, 461.1169610196503, 462.99074018520116, 467.01692936369136, 468.08722990169304, 472.775431669954, 565.8839937812738, 567.9744411501492, 569.8517487263168, 610.5207412181505, 624.3506752976854, 7677.232276772278, 7799.844003120019, 8221.465604735673, 8272.710455134042, 8304.875426868603, 592.2448877680991, 598.332858862374, 600.4145957691303, 617.8887800196007, 621.4624349937362, 3878.83634909526, 3990.3817491702757, 3997.560268370477, 4066.3900414937402, 4110.917781644351, 67516.0, 69665.0, 70254.64872675604, 71177.64411177911, 71902.64048679723, 45400.54599454029, 48087.0, 54198.72900635472, 61200.38799611947, 61861.38138618556, 42871.57128428676, 49652.75173624109, 51030.234546481835, 51566.0, 52052.47947520628, 7881.0, 7924.366050715814, 8300.294474969694, 8396.328293736364, 8455.719621427414, 34178.48732268968, 34271.14322141966, 35167.82416088005, 35882.8205858969, 35984.64015359813, 45116.774416129025, 49269.0, 49300.50699493103, 50159.0, 50498.74750626223, 10840.403777792297, 10860.619878304222, 11117.722056948642, 11122.944385278022, 11353.54585816558, 11982.400879955792, 12279.69300767488, 12416.503339866304, 12450.937745311216, 13857.307134643026, 11555.537778489102, 11788.76422471563, 12062.638120856389, 12096.395180240777, 12172.391380430767, 11156.442177891235, 11616.535338586362, 11798.410079496163, 11940.223885447418, 11962.282263063897, 10651.733706657396, 10657.786844263226, 10733.302335348177, 12597.992160626945, 12622.0, 10170.74573135648, 10383.221258405802, 10442.738931526468, 10480.63317783874, 10694.144468442662, 9030.909690903007, 9817.950910245403, 10097.141742951637, 10190.0, 10206.387616742722], "value": 6275.231279325904, "unit": "score"}], "name": "dromaeo_css", "value": 6178.09803467752, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 117.0, 118.0, 116.0, 117.0, 119.0, 119.0, 116.0, 115.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 133.0, 137.0, 171.0, 130.0, 130.0, 133.0, 132.0, 161.0, 130.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 104.0, 77.0, 80.0, 78.0, 78.0, 93.0, 77.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 117.0, 84.0, 84.0, 83.0, 103.0, 83.0, 84.0, 108.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 64.0, 64.0, 66.0, 64.0, 64.0, 64.0, 65.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 95.0, 92.0, 88.0, 91.0, 92.0, 85.0, 85.0, 92.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 155.0, 151.0, 153.0, 142.0, 140.0, 139.0, 143.0, 182.0, 144.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 178.0, 178.0, 179.0, 178.0, 179.0, 178.0, 176.0, 176.0, 178.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 70.0, 70.0, 73.0, 73.0, 71.0, 71.0, 70.0, 70.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1570.2, "alertThreshold": 2.0}]} 18:42:46 INFO - Return code: 0 18:42:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:46 INFO - # TBPL SUCCESS # 18:42:46 INFO - Running post-action listener: _resource_record_post_action 18:42:46 INFO - [mozharness: 2016-12-01 02:42:46.993670Z] Finished run-tests step (success) 18:42:46 INFO - Running post-run listener: _resource_record_post_run 18:42:47 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 403525632; Read time: 12; Write time: 263436 18:42:47 INFO - TinderboxPrint: CPU usage
13.1% 18:42:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:42:47 INFO - TinderboxPrint: I/O write bytes / time
403,525,632 / 263,436 18:42:47 INFO - TinderboxPrint: CPU idle
8,350.7 (86.9%) 18:42:47 INFO - TinderboxPrint: CPU user
1,206.8 (12.6%) 18:42:47 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99983360; Read time: 0; Write time: 108220 18:42:47 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 292249600; Read time: 0; Write time: 152676 18:42:47 INFO - Running post-run listener: _upload_blobber_files 18:42:47 INFO - Blob upload gear active. 18:42:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:47 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 18:42:47 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:42:47 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:42:48 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:42:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:42:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:48 INFO - (blobuploader) - INFO - Done attempting. 18:42:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:42:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:42:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:50 INFO - (blobuploader) - INFO - Done attempting. 18:42:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:42:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:55 INFO - (blobuploader) - INFO - Done attempting. 18:42:55 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:55 INFO - Return code: 0 18:42:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f3c48e0a3ef3d07817f29f90b61a543cef284ab6c2358a4d0cd88511c3082bcf4417d6826e193f990a7128408dddfb648ad5ea4d7b9c6bcf3ab2a36f702694e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5707f167354c72a48d3273f2b210984e9de23518d3c1c4a6292d57e537361c2fe8fccf7e16beb13fd836c5f1c86db7638c093fddd73ddfde5d05c8082133a8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a9fa092dd08d57497852f9031f54dc95c3694c96fe3f30d360d5ec7b4948a630d5ec19f36b964dc33a7840acfd565b61378f02d0be13ac2abbd04924c9f55fcb"} 18:42:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:55 INFO - Contents: 18:42:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f3c48e0a3ef3d07817f29f90b61a543cef284ab6c2358a4d0cd88511c3082bcf4417d6826e193f990a7128408dddfb648ad5ea4d7b9c6bcf3ab2a36f702694e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5707f167354c72a48d3273f2b210984e9de23518d3c1c4a6292d57e537361c2fe8fccf7e16beb13fd836c5f1c86db7638c093fddd73ddfde5d05c8082133a8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a9fa092dd08d57497852f9031f54dc95c3694c96fe3f30d360d5ec7b4948a630d5ec19f36b964dc33a7840acfd565b61378f02d0be13ac2abbd04924c9f55fcb"} 18:42:55 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:55 INFO - Copying logs to upload dir... 18:42:55 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.666764 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-11-30 18:42:55.317044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 18:42:55.321005) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f3c48e0a3ef3d07817f29f90b61a543cef284ab6c2358a4d0cd88511c3082bcf4417d6826e193f990a7128408dddfb648ad5ea4d7b9c6bcf3ab2a36f702694e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5707f167354c72a48d3273f2b210984e9de23518d3c1c4a6292d57e537361c2fe8fccf7e16beb13fd836c5f1c86db7638c093fddd73ddfde5d05c8082133a8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a9fa092dd08d57497852f9031f54dc95c3694c96fe3f30d360d5ec7b4948a630d5ec19f36b964dc33a7840acfd565b61378f02d0be13ac2abbd04924c9f55fcb"} build_url:https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011369 build_url: 'https://queue.taskcluster.net/v1/task/T9iWuOUHRw6bON8PNizotg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1f3c48e0a3ef3d07817f29f90b61a543cef284ab6c2358a4d0cd88511c3082bcf4417d6826e193f990a7128408dddfb648ad5ea4d7b9c6bcf3ab2a36f702694e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5707f167354c72a48d3273f2b210984e9de23518d3c1c4a6292d57e537361c2fe8fccf7e16beb13fd836c5f1c86db7638c093fddd73ddfde5d05c8082133a8f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a9fa092dd08d57497852f9031f54dc95c3694c96fe3f30d360d5ec7b4948a630d5ec19f36b964dc33a7840acfd565b61378f02d0be13ac2abbd04924c9f55fcb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 18:42:55.346869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:42:55.347187) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1480558888.54253-1266290122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 18:42:55.431176) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 18:42:55.431715) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 18:42:55.432142) ========= ========= Total master_lag: 0.18 =========