builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1480633623.36 results: success (0) buildid: 20161201141044 builduid: 73d0e39ec38248699addff58ac2f6e14 revision: 8c2197c2577bb0c5890af7290ee0bfe659175b6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.359375) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.359857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.360198) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.375687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.376021) ========= 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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-01 15:07:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 105M=0s 2016-12-01 15:07:03 (105 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158250 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.575220) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.575651) ========= 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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017276 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:03.625697) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 15:07:03.626050) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8c2197c2577bb0c5890af7290ee0bfe659175b6f --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 8c2197c2577bb0c5890af7290ee0bfe659175b6f --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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-01 15:07:03,676 truncating revision to first 12 chars 2016-12-01 15:07:03,676 Setting DEBUG logging. 2016-12-01 15:07:03,676 attempt 1/10 2016-12-01 15:07:03,676 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8c2197c2577b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 15:07:04,829 unpacking tar archive at: graphics-8c2197c2577b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.466375 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-01 15:07:05.310065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:05.310394) ========= script_repo_revision: 8c2197c2577bb0c5890af7290ee0bfe659175b6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:05.310808) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:05.311080) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 15:07:05.322287) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-01 15:07:05.322687) ========= /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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:07:05 INFO - MultiFileLogger online at 20161201 15:07:05 in /builds/slave/test 15:07:05 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 15:07:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:07:05 INFO - {'append_to_log': False, 15:07:05 INFO - 'base_work_dir': '/builds/slave/test', 15:07:05 INFO - 'blob_upload_branch': 'Graphics', 15:07:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:07:05 INFO - 'branch': 'Graphics', 15:07:05 INFO - 'buildbot_json_path': 'buildprops.json', 15:07:05 INFO - 'config_files': ('talos/linux_config.py',), 15:07:05 INFO - 'default_actions': ('clobber', 15:07:05 INFO - 'read-buildbot-config', 15:07:05 INFO - 'download-and-extract', 15:07:05 INFO - 'populate-webroot', 15:07:05 INFO - 'create-virtualenv', 15:07:05 INFO - 'install', 15:07:05 INFO - 'run-tests'), 15:07:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:07:05 INFO - 'download_minidump_stackwalk': True, 15:07:05 INFO - 'download_symbols': 'ondemand', 15:07:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:07:05 INFO - 'tooltool.py': '/tools/tooltool.py', 15:07:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:07:05 INFO - '/tools/misc-python/virtualenv.py')}, 15:07:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:07:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:07:05 INFO - 'installer_path': 'installer.exe', 15:07:05 INFO - 'log_level': 'info', 15:07:05 INFO - 'log_name': 'talos', 15:07:05 INFO - 'log_to_console': True, 15:07:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:07:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:07:05 INFO - 'opt_config_files': (), 15:07:05 INFO - 'pip_index': False, 15:07:05 INFO - 'sps_profile': False, 15:07:05 INFO - 'sps_profile_interval': 0, 15:07:05 INFO - 'suite': 'dromaeojs', 15:07:05 INFO - 'system_bits': '32', 15:07:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:07:05 INFO - 'title': 'talos-linux64-ix-014', 15:07:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:07:05 INFO - 'use_talos_json': True, 15:07:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:07:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:07:05 INFO - 'work_dir': 'build'} 15:07:05 INFO - [mozharness: 2016-12-01 23:07:05.611482Z] Running clobber step. 15:07:05 INFO - Running pre-action listener: _resource_record_pre_action 15:07:05 INFO - Running main action method: clobber 15:07:05 INFO - rmtree: /builds/slave/test/build 15:07:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:07:06 INFO - Running post-action listener: _resource_record_post_action 15:07:06 INFO - [mozharness: 2016-12-01 23:07:06.026600Z] Finished clobber step (success) 15:07:06 INFO - [mozharness: 2016-12-01 23:07:06.026700Z] Running read-buildbot-config step. 15:07:06 INFO - Running pre-action listener: _resource_record_pre_action 15:07:06 INFO - Running main action method: read_buildbot_config 15:07:06 INFO - Using buildbot properties: 15:07:06 INFO - { 15:07:06 INFO - "project": "", 15:07:06 INFO - "product": "firefox", 15:07:06 INFO - "script_repo_revision": "default", 15:07:06 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 15:07:06 INFO - "repository": "", 15:07:06 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 15:07:06 INFO - "buildid": "20161201141044", 15:07:06 INFO - "pgo_build": "False", 15:07:06 INFO - "basedir": "/builds/slave/test", 15:07:06 INFO - "buildnumber": 31, 15:07:06 INFO - "slavename": "talos-linux64-ix-014", 15:07:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:07:06 INFO - "platform": "ubuntu64_hw", 15:07:06 INFO - "scheduler": "tests-graphics-linux64-talos", 15:07:06 INFO - "branch": "graphics", 15:07:06 INFO - "repo_path": "projects/graphics", 15:07:06 INFO - "revision": "8c2197c2577bb0c5890af7290ee0bfe659175b6f", 15:07:06 INFO - "stage_platform": "linux64", 15:07:06 INFO - "builduid": "73d0e39ec38248699addff58ac2f6e14", 15:07:06 INFO - "slavebuilddir": "test" 15:07:06 INFO - } 15:07:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 15:07:06 INFO - Running post-action listener: _resource_record_post_action 15:07:06 INFO - [mozharness: 2016-12-01 23:07:06.029140Z] Finished read-buildbot-config step (success) 15:07:06 INFO - [mozharness: 2016-12-01 23:07:06.029229Z] Running download-and-extract step. 15:07:06 INFO - Running pre-action listener: _resource_record_pre_action 15:07:06 INFO - Running main action method: download_and_extract 15:07:06 INFO - mkdir: /builds/slave/test/build/tests 15:07: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')]} 15:07:06 INFO - trying https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:07:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/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 15:07:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/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 15:07:06 INFO - Downloaded 1361 bytes. 15:07:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 15:07:06 INFO - Using the following test package requirements: 15:07:06 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:07:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:07:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:07:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'jsshell-linux-x86_64.zip'], 15:07:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:07:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 15:07:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:07:06 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:07:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:07:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 15:07:06 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:07:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:07:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:07:07 INFO - Http code: 200 15:07:07 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 15:07:07 INFO - x-amz-cf-id: FDHWIXQjdhm6TpmI73lrbMSKEVsgsW-nwlym-WZpyHE_lvsgrJpFyQ== 15:07:07 INFO - x-amz-version-id: XKKAHkCJePxsVztn.gE3i.LKRr_6XFEg 15:07:07 INFO - Expected file size: 20217576 15:07:07 INFO - Obtained file size: 20217576 15:07: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 15:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 15:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 15:07:12 INFO - Http code: 200 15:07:12 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 15:07:12 INFO - x-amz-cf-id: FoVXAWivPzio8ij40euvBVyjaTk7KvCoaLw8GQV-Cm1FBgluXeRtGw== 15:07:12 INFO - x-amz-version-id: XKKAHkCJePxsVztn.gE3i.LKRr_6XFEg 15:07:12 INFO - Expected file size: 20217576 15:07:12 INFO - Obtained file size: 20217576 15:07:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 15:07:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:07:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 15:07:17 INFO - Http code: 200 15:07:17 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 15:07:17 INFO - x-amz-cf-id: ArTTZBdcygpmE1GObIwz-orPk8Fk9ahF4tFY26YTNWoHPFoxEZiO7Q== 15:07:17 INFO - x-amz-version-id: l5ASgB.0Ejh3FcWwjnXWXk_u8lAGA.9D 15:07:17 INFO - Expected file size: 14409702 15:07:17 INFO - Obtained file size: 14409702 15:07:17 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')]} 15:07:17 INFO - trying https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:07:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/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 15:07:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/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 15:07:19 INFO - Downloaded 55686350 bytes. 15:07:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:07:19 INFO - mkdir: /builds/slave/test/properties 15:07:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:07:19 INFO - Writing to file /builds/slave/test/properties/build_url 15:07:19 INFO - Contents: 15:07:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 15:07:19 INFO - Running post-action listener: _resource_record_post_action 15:07:19 INFO - Running post-action listener: set_extra_try_arguments 15:07:19 INFO - [mozharness: 2016-12-01 23:07:19.790887Z] Finished download-and-extract step (success) 15:07:19 INFO - [mozharness: 2016-12-01 23:07:19.791116Z] Running populate-webroot step. 15:07:19 INFO - Running pre-action listener: _resource_record_pre_action 15:07:19 INFO - Running main action method: populate_webroot 15:07:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:07:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:07:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:07:19 INFO - '--tppagecycles', 15:07:19 INFO - '7'], 15:07:19 INFO - 'tests': ['tsvgm']}}, 15:07:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:07:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:07:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:07:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:07:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['damp', 'tps']}, 15:07:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'tests': ['damp', 'tps']}, 15:07:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['dromaeo_dom']}, 15:07:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:07:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['basic_compositor_video']}, 15:07:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:07:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'other-e10s': {'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['a11yr', 15:07:19 INFO - 'ts_paint', 15:07:19 INFO - 'tpaint', 15:07:19 INFO - 'sessionrestore', 15:07:19 INFO - 'sessionrestore_no_auto_restore', 15:07:19 INFO - 'tabpaint']}, 15:07:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['tsvgx', 15:07:19 INFO - 'tsvgr_opacity', 15:07:19 INFO - 'tart', 15:07:19 INFO - 'tscrollx', 15:07:19 INFO - 'cart', 15:07:19 INFO - 'tsvg_static']}, 15:07:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:07:19 INFO - 'tsvgr_opacity', 15:07:19 INFO - 'tart', 15:07:19 INFO - 'tscrollx', 15:07:19 INFO - 'cart', 15:07:19 INFO - 'tsvg_static']}, 15:07:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'talos_options': ['--disable-e10s'], 15:07:19 INFO - 'tests': ['tp5o']}, 15:07:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:07:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'talos_options': ['--disable-e10s', 15:07:19 INFO - '--xperf_path', 15:07:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:19 INFO - 'tests': ['tp5n']}, 15:07:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:07:19 INFO - 'talos_options': ['--xperf_path', 15:07:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:19 INFO - 'tests': ['tp5n']}}, 15:07:19 INFO - 'talos.zip': {'path': '', 15:07:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:07:19 INFO - Running post-action listener: _resource_record_post_action 15:07:19 INFO - [mozharness: 2016-12-01 23:07:19.812296Z] Finished populate-webroot step (success) 15:07:19 INFO - [mozharness: 2016-12-01 23:07:19.812384Z] Running create-virtualenv step. 15:07:19 INFO - Running pre-action listener: _resource_record_pre_action 15:07:19 INFO - Running main action method: create_virtualenv 15:07:19 INFO - Creating virtualenv /builds/slave/test/build/venv 15:07:19 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 15:07:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:07:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:07:19 INFO - Using real prefix '/usr' 15:07:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:07:20 INFO - Installing distribute.............................................................................................................................................................................................done. 15:07:22 INFO - Installing pip.................done. 15:07:22 INFO - Return code: 0 15:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:07:23 INFO - Reading from file tmpfile_stdout 15:07:23 INFO - Output received: 15:07:23 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) 15:07:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07: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', 'pip>=1.5'] in /builds/slave/test/build 15:07: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 pip>=1.5 15:07:23 INFO - Using env: {'DISPLAY': ':0', 15:07:23 INFO - 'HOME': '/home/cltbld', 15:07:23 INFO - 'LANG': 'en_US.UTF-8', 15:07:23 INFO - 'LANGUAGE': 'en_US:en', 15:07:23 INFO - 'LOGNAME': 'cltbld', 15:07:23 INFO - 'MAIL': '/var/mail/cltbld', 15:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:23 INFO - 'MOZ_NO_REMOTE': '1', 15:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:23 INFO - 'NO_EM_RESTART': '1', 15:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:23 INFO - 'PWD': '/builds/slave/test', 15:07:23 INFO - 'SHELL': '/bin/bash', 15:07:23 INFO - 'SHLVL': '1', 15:07:23 INFO - 'TERM': 'linux', 15:07:23 INFO - 'TMOUT': '86400', 15:07:23 INFO - 'USER': 'cltbld', 15:07:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:23 INFO - '_': '/tools/buildbot/bin/python'} 15:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07: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 15:07:23 INFO - Cleaning up... 15:07:23 INFO - Return code: 0 15:07:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07: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 15:07: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 15:07:23 INFO - Using env: {'DISPLAY': ':0', 15:07:23 INFO - 'HOME': '/home/cltbld', 15:07:23 INFO - 'LANG': 'en_US.UTF-8', 15:07:23 INFO - 'LANGUAGE': 'en_US:en', 15:07:23 INFO - 'LOGNAME': 'cltbld', 15:07:23 INFO - 'MAIL': '/var/mail/cltbld', 15:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:23 INFO - 'MOZ_NO_REMOTE': '1', 15:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:23 INFO - 'NO_EM_RESTART': '1', 15:07:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:23 INFO - 'PWD': '/builds/slave/test', 15:07:23 INFO - 'SHELL': '/bin/bash', 15:07:23 INFO - 'SHLVL': '1', 15:07:23 INFO - 'TERM': 'linux', 15:07:23 INFO - 'TMOUT': '86400', 15:07:23 INFO - 'USER': 'cltbld', 15:07:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:23 INFO - '_': '/tools/buildbot/bin/python'} 15:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:23 INFO - Downloading/unpacking psutil>=3.1.1 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:07:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:25 INFO - Installing collected packages: psutil 15:07:25 INFO - Running setup.py install for psutil 15:07:25 INFO - building 'psutil._psutil_linux' extension 15:07: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 15:07:26 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 15:07:26 INFO - building 'psutil._psutil_posix' extension 15:07:26 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 15:07:26 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 15:07:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:26 INFO - Successfully installed psutil 15:07:26 INFO - Cleaning up... 15:07:27 INFO - Return code: 0 15:07:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:27 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:27 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 15:07:27 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 15:07:27 INFO - Using env: {'DISPLAY': ':0', 15:07:27 INFO - 'HOME': '/home/cltbld', 15:07:27 INFO - 'LANG': 'en_US.UTF-8', 15:07:27 INFO - 'LANGUAGE': 'en_US:en', 15:07:27 INFO - 'LOGNAME': 'cltbld', 15:07:27 INFO - 'MAIL': '/var/mail/cltbld', 15:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:27 INFO - 'MOZ_NO_REMOTE': '1', 15:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:27 INFO - 'NO_EM_RESTART': '1', 15:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:27 INFO - 'PWD': '/builds/slave/test', 15:07:27 INFO - 'SHELL': '/bin/bash', 15:07:27 INFO - 'SHLVL': '1', 15:07:27 INFO - 'TERM': 'linux', 15:07:27 INFO - 'TMOUT': '86400', 15:07:27 INFO - 'USER': 'cltbld', 15:07:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:27 INFO - '_': '/tools/buildbot/bin/python'} 15:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:07:27 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 15:07:27 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 15:07:27 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 15:07:27 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 15:07:27 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 15:07:27 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 15:07:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:07:29 INFO - Installing collected packages: mozsystemmonitor 15:07:29 INFO - Running setup.py install for mozsystemmonitor 15:07:29 INFO - Successfully installed mozsystemmonitor 15:07:29 INFO - Cleaning up... 15:07:29 INFO - Return code: 0 15:07:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:07:29 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')]} 15:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:29 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')]} 15:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:29 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:29 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 15:07:29 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 15:07:29 INFO - Using env: {'DISPLAY': ':0', 15:07:29 INFO - 'HOME': '/home/cltbld', 15:07:29 INFO - 'LANG': 'en_US.UTF-8', 15:07:29 INFO - 'LANGUAGE': 'en_US:en', 15:07:29 INFO - 'LOGNAME': 'cltbld', 15:07:29 INFO - 'MAIL': '/var/mail/cltbld', 15:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:29 INFO - 'MOZ_NO_REMOTE': '1', 15:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:29 INFO - 'NO_EM_RESTART': '1', 15:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:29 INFO - 'PWD': '/builds/slave/test', 15:07:29 INFO - 'SHELL': '/bin/bash', 15:07:29 INFO - 'SHLVL': '1', 15:07:29 INFO - 'TERM': 'linux', 15:07:29 INFO - 'TMOUT': '86400', 15:07:29 INFO - 'USER': 'cltbld', 15:07:29 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:29 INFO - '_': '/tools/buildbot/bin/python'} 15:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:29 INFO - Downloading/unpacking jsonschema==2.5.1 15:07:29 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 15:07:29 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 15:07:29 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 15:07:29 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 15:07:29 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 15:07:29 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 15:07:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:07:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:07:31 INFO - warning: no files found matching '*.txt' 15:07:31 INFO - no previously-included directories found matching 'build' 15:07:31 INFO - no previously-included directories found matching 'dist' 15:07:31 INFO - no previously-included directories found matching '.git*' 15:07:31 INFO - Installing collected packages: jsonschema, functools32 15:07:31 INFO - Running setup.py install for functools32 15:07:32 INFO - warning: no files found matching '*.txt' 15:07:32 INFO - no previously-included directories found matching 'build' 15:07:32 INFO - no previously-included directories found matching 'dist' 15:07:32 INFO - no previously-included directories found matching '.git*' 15:07:32 INFO - Successfully installed jsonschema functools32 15:07:32 INFO - Cleaning up... 15:07:32 INFO - Return code: 0 15:07:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:32 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 15:07:32 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 15:07:32 INFO - Using env: {'DISPLAY': ':0', 15:07:32 INFO - 'HOME': '/home/cltbld', 15:07:32 INFO - 'LANG': 'en_US.UTF-8', 15:07:32 INFO - 'LANGUAGE': 'en_US:en', 15:07:32 INFO - 'LOGNAME': 'cltbld', 15:07:32 INFO - 'MAIL': '/var/mail/cltbld', 15:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:32 INFO - 'MOZ_NO_REMOTE': '1', 15:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:32 INFO - 'NO_EM_RESTART': '1', 15:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:32 INFO - 'PWD': '/builds/slave/test', 15:07:32 INFO - 'SHELL': '/bin/bash', 15:07:32 INFO - 'SHLVL': '1', 15:07:32 INFO - 'TERM': 'linux', 15:07:32 INFO - 'TMOUT': '86400', 15:07:32 INFO - 'USER': 'cltbld', 15:07:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:32 INFO - '_': '/tools/buildbot/bin/python'} 15:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:07:32 INFO - Cleaning up... 15:07:32 INFO - Return code: 0 15:07:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:32 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 15:07:32 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 15:07:32 INFO - Using env: {'DISPLAY': ':0', 15:07:32 INFO - 'HOME': '/home/cltbld', 15:07:32 INFO - 'LANG': 'en_US.UTF-8', 15:07:32 INFO - 'LANGUAGE': 'en_US:en', 15:07:32 INFO - 'LOGNAME': 'cltbld', 15:07:32 INFO - 'MAIL': '/var/mail/cltbld', 15:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:32 INFO - 'MOZ_NO_REMOTE': '1', 15:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:32 INFO - 'NO_EM_RESTART': '1', 15:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:32 INFO - 'PWD': '/builds/slave/test', 15:07:32 INFO - 'SHELL': '/bin/bash', 15:07:32 INFO - 'SHLVL': '1', 15:07:32 INFO - 'TERM': 'linux', 15:07:32 INFO - 'TMOUT': '86400', 15:07:32 INFO - 'USER': 'cltbld', 15:07:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:32 INFO - '_': '/tools/buildbot/bin/python'} 15:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:32 INFO - Downloading/unpacking blobuploader==1.2.4 15:07:32 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 15:07:32 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 15:07:32 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 15:07:32 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 15:07:32 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 15:07:32 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 15:07:34 INFO - Downloading blobuploader-1.2.4.tar.gz 15:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:07:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07: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 15:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:07:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:07:35 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 15:07:35 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 15:07:35 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 15:07:35 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 15:07:35 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 15:07:35 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 15:07:35 INFO - Downloading docopt-0.6.1.tar.gz 15:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:07:35 INFO - Installing collected packages: blobuploader, requests, docopt 15:07:35 INFO - Running setup.py install for blobuploader 15:07:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:07:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:07:35 INFO - Running setup.py install for requests 15:07:35 INFO - Running setup.py install for docopt 15:07:36 INFO - Successfully installed blobuploader requests docopt 15:07:36 INFO - Cleaning up... 15:07:36 INFO - Return code: 0 15:07:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:36 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')]} 15:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:36 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')]} 15:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:36 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:36 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 15:07:36 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 15:07:36 INFO - Using env: {'DISPLAY': ':0', 15:07:36 INFO - 'HOME': '/home/cltbld', 15:07:36 INFO - 'LANG': 'en_US.UTF-8', 15:07:36 INFO - 'LANGUAGE': 'en_US:en', 15:07:36 INFO - 'LOGNAME': 'cltbld', 15:07:36 INFO - 'MAIL': '/var/mail/cltbld', 15:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:36 INFO - 'MOZ_NO_REMOTE': '1', 15:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:36 INFO - 'NO_EM_RESTART': '1', 15:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:36 INFO - 'PWD': '/builds/slave/test', 15:07:36 INFO - 'SHELL': '/bin/bash', 15:07:36 INFO - 'SHLVL': '1', 15:07:36 INFO - 'TERM': 'linux', 15:07:36 INFO - 'TMOUT': '86400', 15:07:36 INFO - 'USER': 'cltbld', 15:07:36 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:36 INFO - '_': '/tools/buildbot/bin/python'} 15:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:36 INFO - Running setup.py (path:/tmp/pip-SISJZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:36 INFO - Running setup.py (path:/tmp/pip-XgU3pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:36 INFO - Running setup.py (path:/tmp/pip-C7oYg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:36 INFO - Running setup.py (path:/tmp/pip-p0Cxj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:36 INFO - Running setup.py (path:/tmp/pip-cNZeck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:36 INFO - Running setup.py (path:/tmp/pip-7Xoq9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:36 INFO - Running setup.py (path:/tmp/pip-xkh_1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:36 INFO - Running setup.py (path:/tmp/pip-CHGJyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:36 INFO - Running setup.py (path:/tmp/pip-KAyVRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:36 INFO - Running setup.py (path:/tmp/pip-AotYFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:36 INFO - Running setup.py (path:/tmp/pip-QEzhEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:37 INFO - Running setup.py (path:/tmp/pip-eGfYmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:37 INFO - Running setup.py (path:/tmp/pip-Z9CAMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:37 INFO - Running setup.py (path:/tmp/pip-LKwd8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:37 INFO - Running setup.py (path:/tmp/pip-bv4DYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:37 INFO - Running setup.py (path:/tmp/pip-ufEHMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:37 INFO - Running setup.py (path:/tmp/pip-pbCAJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:07:37 INFO - Running setup.py install for manifestparser 15:07:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:07:37 INFO - Running setup.py install for mozcrash 15:07:37 INFO - Running setup.py install for mozdebug 15:07:37 INFO - Running setup.py install for mozdevice 15:07:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:07:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:07:37 INFO - Running setup.py install for mozfile 15:07:37 INFO - Running setup.py install for mozhttpd 15:07:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:07:37 INFO - Running setup.py install for mozinfo 15:07:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for mozInstall 15:07:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for mozleak 15:07:38 INFO - Running setup.py install for mozlog 15:07:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for moznetwork 15:07:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for mozprocess 15:07:38 INFO - Running setup.py install for mozprofile 15:07:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for mozrunner 15:07:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:07:38 INFO - Running setup.py install for mozscreenshot 15:07:38 INFO - Running setup.py install for moztest 15:07:39 INFO - Running setup.py install for mozversion 15:07:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:07:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:07:39 INFO - Cleaning up... 15:07:39 INFO - Return code: 0 15:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:39 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')]} 15:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:39 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')]} 15:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:39 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:39 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 15:07:39 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 15:07:39 INFO - Using env: {'DISPLAY': ':0', 15:07:39 INFO - 'HOME': '/home/cltbld', 15:07:39 INFO - 'LANG': 'en_US.UTF-8', 15:07:39 INFO - 'LANGUAGE': 'en_US:en', 15:07:39 INFO - 'LOGNAME': 'cltbld', 15:07:39 INFO - 'MAIL': '/var/mail/cltbld', 15:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:39 INFO - 'MOZ_NO_REMOTE': '1', 15:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:39 INFO - 'NO_EM_RESTART': '1', 15:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:39 INFO - 'PWD': '/builds/slave/test', 15:07:39 INFO - 'SHELL': '/bin/bash', 15:07:39 INFO - 'SHLVL': '1', 15:07:39 INFO - 'TERM': 'linux', 15:07:39 INFO - 'TMOUT': '86400', 15:07:39 INFO - 'USER': 'cltbld', 15:07:39 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:39 INFO - '_': '/tools/buildbot/bin/python'} 15:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:39 INFO - Running setup.py (path:/tmp/pip-AU674t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:39 INFO - Running setup.py (path:/tmp/pip-iv3m1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:39 INFO - Running setup.py (path:/tmp/pip-rJj1sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:39 INFO - Running setup.py (path:/tmp/pip-Pbbo6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:39 INFO - Running setup.py (path:/tmp/pip-4vrVJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:39 INFO - Running setup.py (path:/tmp/pip-wC7Cx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:39 INFO - Running setup.py (path:/tmp/pip-Y_kdsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:39 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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:39 INFO - Running setup.py (path:/tmp/pip-fUozF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07: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)) 15:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:39 INFO - Running setup.py (path:/tmp/pip-UqQ37r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:40 INFO - Running setup.py (path:/tmp/pip-NHmhyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:40 INFO - Running setup.py (path:/tmp/pip-wTIvoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:40 INFO - Running setup.py (path:/tmp/pip-7LtwJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:40 INFO - Running setup.py (path:/tmp/pip-ZKcAdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:40 INFO - Running setup.py (path:/tmp/pip-g9o0Jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:40 INFO - Running setup.py (path:/tmp/pip-yQcadh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:40 INFO - Running setup.py (path:/tmp/pip-_FPnJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:40 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)) 15:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:40 INFO - Running setup.py (path:/tmp/pip-RlU05J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:40 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)) 15:07:40 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)) 15:07:40 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)) 15:07:40 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)) 15:07:40 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)) 15:07:40 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)) 15:07:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:07:40 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 15:07:40 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 15:07:40 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 15:07:40 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 15:07:40 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 15:07:40 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 15:07:42 INFO - Downloading blessings-1.6.tar.gz 15:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:07:42 INFO - Installing collected packages: blessings 15:07:42 INFO - Running setup.py install for blessings 15:07:42 INFO - Successfully installed blessings 15:07:42 INFO - Cleaning up... 15:07:42 INFO - Return code: 0 15:07:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:07:43 INFO - Reading from file tmpfile_stdout 15:07:43 INFO - Current package versions: 15:07:43 INFO - argparse == 1.2.1 15:07:43 INFO - blessings == 1.6 15:07:43 INFO - blobuploader == 1.2.4 15:07:43 INFO - docopt == 0.6.1 15:07:43 INFO - functools32 == 3.2.3-2 15:07:43 INFO - jsonschema == 2.5.1 15:07:43 INFO - manifestparser == 1.1 15:07:43 INFO - mozInstall == 1.12 15:07:43 INFO - mozcrash == 1.0 15:07:43 INFO - mozdebug == 0.1 15:07:43 INFO - mozdevice == 0.49 15:07:43 INFO - mozfile == 1.2 15:07:43 INFO - mozhttpd == 0.7 15:07:43 INFO - mozinfo == 0.9 15:07:43 INFO - mozleak == 0.1 15:07:43 INFO - mozlog == 3.4 15:07:43 INFO - moznetwork == 0.27 15:07:43 INFO - mozprocess == 0.23 15:07:43 INFO - mozprofile == 0.28 15:07:43 INFO - mozrunner == 6.13 15:07:43 INFO - mozscreenshot == 0.1 15:07:43 INFO - mozsystemmonitor == 0.3 15:07:43 INFO - moztest == 0.7 15:07:43 INFO - mozversion == 1.4 15:07:43 INFO - psutil == 3.1.1 15:07:43 INFO - requests == 1.2.3 15:07:43 INFO - wsgiref == 0.1.2 15:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:43 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')]} 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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')]} 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:43 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 15:07:43 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 15:07:43 INFO - Using env: {'DISPLAY': ':0', 15:07:43 INFO - 'HOME': '/home/cltbld', 15:07:43 INFO - 'LANG': 'en_US.UTF-8', 15:07:43 INFO - 'LANGUAGE': 'en_US:en', 15:07:43 INFO - 'LOGNAME': 'cltbld', 15:07:43 INFO - 'MAIL': '/var/mail/cltbld', 15:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:43 INFO - 'MOZ_NO_REMOTE': '1', 15:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:43 INFO - 'NO_EM_RESTART': '1', 15:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:43 INFO - 'PWD': '/builds/slave/test', 15:07:43 INFO - 'SHELL': '/bin/bash', 15:07:43 INFO - 'SHLVL': '1', 15:07:43 INFO - 'TERM': 'linux', 15:07:43 INFO - 'TMOUT': '86400', 15:07:43 INFO - 'USER': 'cltbld', 15:07:43 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:43 INFO - '_': '/tools/buildbot/bin/python'} 15:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 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)) 15:07:43 INFO - Cleaning up... 15:07:43 INFO - Return code: 0 15:07:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:07:43 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')]} 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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')]} 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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 0x7fc214a8ce30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1403b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x159f130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, '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': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', '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 15:07:43 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 15:07:43 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 15:07:43 INFO - Using env: {'DISPLAY': ':0', 15:07:43 INFO - 'HOME': '/home/cltbld', 15:07:43 INFO - 'LANG': 'en_US.UTF-8', 15:07:43 INFO - 'LANGUAGE': 'en_US:en', 15:07:43 INFO - 'LOGNAME': 'cltbld', 15:07:43 INFO - 'MAIL': '/var/mail/cltbld', 15:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:43 INFO - 'MOZ_NO_REMOTE': '1', 15:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:43 INFO - 'NO_EM_RESTART': '1', 15:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:43 INFO - 'PWD': '/builds/slave/test', 15:07:43 INFO - 'SHELL': '/bin/bash', 15:07:43 INFO - 'SHLVL': '1', 15:07:43 INFO - 'TERM': 'linux', 15:07:43 INFO - 'TMOUT': '86400', 15:07:43 INFO - 'USER': 'cltbld', 15:07:43 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:43 INFO - '_': '/tools/buildbot/bin/python'} 15:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:07:43 INFO - Cleaning up... 15:07:43 INFO - Return code: 0 15:07:43 INFO - Running post-action listener: _resource_record_post_action 15:07:43 INFO - Running post-action listener: _start_resource_monitoring 15:07:43 INFO - Starting resource monitoring. 15:07:43 INFO - [mozharness: 2016-12-01 23:07:43.593798Z] Finished create-virtualenv step (success) 15:07:43 INFO - [mozharness: 2016-12-01 23:07:43.594751Z] Running install step. 15:07:43 INFO - Running pre-action listener: _resource_record_pre_action 15:07:43 INFO - Running main action method: install 15:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:07:43 INFO - Reading from file tmpfile_stdout 15:07:43 INFO - Detecting whether we're running mozinstall >=1.0... 15:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:07:43 INFO - Reading from file tmpfile_stdout 15:07:43 INFO - Output received: 15:07:43 INFO - Usage: mozinstall [options] installer 15:07:43 INFO - Options: 15:07:43 INFO - -h, --help show this help message and exit 15:07:43 INFO - -d DEST, --destination=DEST 15:07:43 INFO - Directory to install application into. [default: 15:07:43 INFO - "/builds/slave/test"] 15:07:43 INFO - --app=APP Application being installed. [default: firefox] 15:07:43 INFO - mkdir: /builds/slave/test/build/application 15:07:43 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'] 15:07:43 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 15:07:59 INFO - Reading from file tmpfile_stdout 15:07:59 INFO - Output received: 15:07:59 INFO - /builds/slave/test/build/application/firefox/firefox 15:07:59 INFO - Running post-action listener: _resource_record_post_action 15:07:59 INFO - [mozharness: 2016-12-01 23:07:59.924165Z] Finished install step (success) 15:07:59 INFO - [mozharness: 2016-12-01 23:07:59.924411Z] Running run-tests step. 15:07:59 INFO - Running pre-action listener: _resource_record_pre_action 15:07:59 INFO - Running main action method: run_tests 15:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:07:59 INFO - Python 2.7.3 15:07:59 INFO - Return code: 0 15:07:59 INFO - grabbing minidump binary from tooltool 15:07:59 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')]} 15:07:59 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 0x162dfc0>, '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 0x162eec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x162f350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:07:59 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 15:07:59 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 15:07:59 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 15:08:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:08:00 INFO - Return code: 0 15:08:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:08:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:08:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:08:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:08:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:08:00 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/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:08:00 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/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 15:08:00 INFO - Using env: {'DISPLAY': ':0', 15:08:00 INFO - 'HOME': '/home/cltbld', 15:08:00 INFO - 'LANG': 'en_US.UTF-8', 15:08:00 INFO - 'LANGUAGE': 'en_US:en', 15:08:00 INFO - 'LOGNAME': 'cltbld', 15:08:00 INFO - 'MAIL': '/var/mail/cltbld', 15:08:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:08:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:00 INFO - 'MOZ_NO_REMOTE': '1', 15:08:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:08:00 INFO - 'NO_EM_RESTART': '1', 15:08:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:00 INFO - 'PWD': '/builds/slave/test', 15:08:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:08:00 INFO - 'SHELL': '/bin/bash', 15:08:00 INFO - 'SHLVL': '1', 15:08:00 INFO - 'TERM': 'linux', 15:08:00 INFO - 'TMOUT': '86400', 15:08:00 INFO - 'USER': 'cltbld', 15:08:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840', 15:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:00 INFO - '_': '/tools/buildbot/bin/python'} 15:08:00 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/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 15:08:00 INFO - mozversion application_buildid: 20161201141044 15:08:00 INFO - mozversion application_changeset: 8c2197c2577bb0c5890af7290ee0bfe659175b6f 15:08:00 INFO - mozversion application_display_name: Nightly 15:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:08:00 INFO - mozversion application_name: Firefox 15:08:00 INFO - mozversion application_remotingname: firefox 15:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 15:08:00 INFO - mozversion application_vendor: Mozilla 15:08:00 INFO - mozversion application_version: 53.0a1 15:08:00 INFO - mozversion platform_buildid: 20161201141044 15:08:00 INFO - mozversion platform_changeset: 8c2197c2577bb0c5890af7290ee0bfe659175b6f 15:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 15:08:00 INFO - mozversion platform_version: 53.0a1 15:08:00 INFO - using testdate: 1480633680 15:08:00 INFO - actual date: 1480633680 15:08:00 INFO - starting webserver on 'localhost:46164' 15:08:00 INFO - SUITE-START | Running 2 tests 15:08:00 INFO - TEST-START | dromaeo_css 15:08:00 INFO - operating with platform_type : linux_ 15:08:00 INFO - Initialising browser for dromaeo_css test... 15:08:00 INFO - TEST-INFO | started process 3280 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8AD0RU/profile http://localhost:46164/getInfo.html) 15:08:05 INFO - PROCESS | 3280 | __metrics Screen width/height:1600/1200 15:08:05 INFO - PROCESS | 3280 | colorDepth:24 15:08:05 INFO - PROCESS | 3280 | Browser inner width/height: 1024/697 15:08:05 INFO - PROCESS | 3280 | __metrics 15:08:06 INFO - PROCESS | 3280 | 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" 15:08:06 INFO - PROCESS | 3280 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:07 INFO - PROCESS | 3280 | console.error: PushService: 15:08:07 INFO - PROCESS | 3280 | stateChangeProcessEnqueue: Error transitioning state 15:08:07 INFO - PROCESS | 3280 | UnknownError 15:08:07 INFO - TEST-INFO | 3280: exit 0 15:08:07 INFO - Browser initialized. 15:08:07 INFO - Running cycle 1/1 for dromaeo_css test... 15:08:07 INFO - TEST-INFO | started process 3363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8AD0RU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:20 INFO - PROCESS | 3363 | RSS: Main: 175321088 15:08:20 INFO - PROCESS | 3363 | 15:09:11 INFO - PROCESS | 3363 | 1480633751011 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] 15:11:24 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46164/tests/dromaeo/cssquery-ext.html) 15:11:26 INFO - PROCESS | 3363 | RSS: Main: 338104320 15:11:26 INFO - PROCESS | 3363 | 15:14:29 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-ext.html (next: http://localhost:46164/tests/dromaeo/cssquery-jquery.html) 15:14:30 INFO - PROCESS | 3363 | RSS: Main: 274423808 15:14:30 INFO - PROCESS | 3363 | 15:17:32 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46164/tests/dromaeo/cssquery-mootools.html) 15:17:33 INFO - PROCESS | 3363 | RSS: Main: 221831168 15:17:33 INFO - PROCESS | 3363 | 15:20:35 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46164/tests/dromaeo/cssquery-prototype.html) 15:20:36 INFO - PROCESS | 3363 | RSS: Main: 221990912 15:20:36 INFO - PROCESS | 3363 | 15:23:39 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46164/tests/dromaeo/cssquery-yui.html) 15:23:39 INFO - PROCESS | 3363 | RSS: Main: 225357824 15:23:39 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | Cycle 1(1): loaded http://localhost:46164/tests/dromaeo/cssquery-yui.html (next: http://localhost:46164/tests/dromaeo/cssquery-dojo.html) 15:26:43 INFO - PROCESS | 3363 | RSS: Main: 308887552 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | __start_tp_report 15:26:43 INFO - PROCESS | 3363 | _x_x_mozilla_page_load 15:26:43 INFO - PROCESS | 3363 | _x_x_mozilla_page_load_details 15:26:43 INFO - PROCESS | 3363 | |i|pagename|runs| 15:26:43 INFO - PROCESS | 3363 | |0;dojo.html;2565.9101931432397;2590.1193594177976;2601.791856651467;2603.635491031256;2612.2424496895887;4577.29051996941;4624.884377890555;4710.599599034082;4720.527947205282;4728.598069164121;4651.232546629811;4677.345171675959;4683.601893839019;4703.90592188156;4751.216049351853;4305.440292761952;4310.612044915957;4357.716748411359;4372.322290045048;4385.627221686144;4325.589069038437;4372.868813935587;4379.671524635649;4412.955870441287;4436.0462500562535;4404.779761011969;4474.843380481684;4478.686491945565;4485.910281794362;4495.797689103998;4234.513031001413;4249.851255206053;4262.552431994627;4265.189613973335;4369.410129632491;4288.485381754178;4289.764062976535;4289.935650965238;4339.82640694375;4341.848035318749;4664.673472856902;4676.064787042571;4694.201985662411;4712.151812673717;4713.410823647044;4839.588634966032;4874.025194961022;5017.37282839645;5033.622478314104;5045.217991211368;4966.627502937295;4985.825496107619;5049.520295571915;5054.11552978231;5085.5931525478245;4673.252279635276;4679.1577516046755;4682.157211701926;4700.858974230779;4728.148933192058;5116.181410974226;5126.000429916131;5127.102757017507;5133.383993920754;5177.456367081478;4827.662063655511;4843.128236917319;4853.95146048542;4854.053459575349;4869.050535145683;4960.801567937314;4961.8511444656715;4964.528369804862;4991.550760431579;5008.874778130503;4899.265110233494;4905.9264111038365;4906.067847109038;4960.603151747851;4968.90062198754;4690;4699.718016918996;4732.834350797774;4893.926591101136;4902.28916807061;4212.53662097169;4221.894452638709;4247.617714405718;4254.957450425456;4307.160103779733;3716.627121009719;3728.776273423549;3730;3754.6433088856515;3756.718246131551;4175.519815221228;4325.329573916048;4335.7181783184;4339.045410009793;4378.058717375779;4880.902381952341;4959.181735013754;4979.800807967641;5018.347614810052;5023.8492845214705;4331.480222373272;4335.804888780012;4335.913281734348;4376.190404775127;4379.978100109479;4557.407537020166;4563.566461186182;4569.497355290981;4573.039661671086;4612.285095810154;4551.158035763262;4576.130535198302;4580.725156490621;4621.884452888705;4692.061587682545;5010.323606313101;5076.0863044652315;5079.898402032012;5125.666831655931;5138.3063286457345;4704.67067305299;4714.104320179155;4715.174844402284;4727.290906364073;4755.334253204624;4781.928271075867;4853.80584776605;5002.124628190125;5005.1241032819835;5043.092243396224;4939.802407903644;4944.456109827845;4960.107180707508;5030.446650868328;5088.211327244283;4880.975595122001;4897.094037603055;4898.608111351155;4914.385701787277;4948.653594248368;4716.198246298069;4890.413150421972;4892.046051020017;4894.094592500399;4937.654364194472;4684.227102528181;4830.227163653799;4859.757012149449;4865.440474345354;4943.382077240345;4987.12725273083;5004.824831130893;5007.474215207497;5008.148614735576;5053.67151135175;4645.3032045193495;4659.138059459011;4659.510751371193;4821.252705830602;4878.658493905393 15:26:43 INFO - PROCESS | 3363 | |1;ext.html;566.1168577019849;569.8461415417837;570.4438172781536;579.4147910610283;581.6742624130487;28325;30674;37132.81433592835;38238.42642360367;38749.61250387495;39335.606643933555;39686.60313396865;39755.20489590206;39799.80100099496;39817.40273895894;7522.849543009136;7676.2323767623075;7680.846383072335;7683.615819209045;7687.192844751305;7400.814979625498;7595;9604.135627793497;9890.653827116052;9897.95051024744;9910.306278560503;9968.601255949754;9983;9984.50077496126;9987.600495980188;9800.117989380955;9823.459709715962;9851.852222216672;9860.80278394428;9872.506374681308;9606.231501479864;9672.08115229052;9673.274504412127;9673.274504412197;9675.419474831533;37539.62460375416;37798;37972;38010.80994595037;38028.04929876775;2899.5070837957596;3070.078976307099;3113.7041981011766;3250.8537115829845;3328;27891.43013235992;28320.575191372147;28367;28836.42327153445;28938;3388.9830550847337;3444.621091679913;3452.154222215548;3542.468629705539;3601.477785721082;254495;254656;254765;254851;254969;50886.982260355326;51266.74366628218;51313.2303015455;51339;51384;53375.199372009454;53396;53411.46588534065;53519.732401337744;53721.46278537243;12090.153689241668;12414.62756117318;12415;12418.565350212699;12456.128071035122;7564.130125035691;7661.61691915402;8670.479771213748;8677.52273624944;8717.912820871838;13559.322033898266;14652.780208296885;14831.332590033475;15059.472918447802;15664.710410813628;6091.177691011745;6111.663858487737;6214.378562143749;6257.217847769029;6258.311585725566;791.5488171742052;848.1306660672859;916.7983043730369;923.2936803345447;936.9927328122296;27473.86263068672;27548.724512754616;27884.302892427448;28008.29979250535;28053.158405247876;898.1070285558443;900.9144131307515;917.4403414473544;923.3859483443434;931.9953400233092;13770.449182032808;14593.635159121108;14630.853691463162;14750.483733069292;14853.331600078023;360945;364227;364774;364832;365480;47665;47729.522704773204;47788.52211477841;47861.28208076882;47862.28206576904;1028.1671845804922;1078.2829418436809;1109.1625822503959;1136.6419577832955;1160.976780464386;24883.751162488144;27787;28708.282292942844;28989.85505072461;29297.41405171842;38396.6160338393;38905;38930.221395572495;38939.80530097331;38944.6105538941;291019;292528;292851;292858;293295;23097.88451057734;24151.51696966086;24182.51634967326;24876.253712388658;25047.87476062608;39775.60224397835;40248.79875600721;40269.39595906122;41143.7942810284;41174.79412602918;34409;34546;34698.65301347055;34973.47539786854;35327.293454131286;34380.656193437746;34383.65616343805;34685.826570866986;34721.826390867885;34759.82620086883;34099.488507672904;34661.65338346685;34940.82529587336;35057.8247108773;35720.82139589285;29850.701492984794;32031.67968320287;32074.386982278476;32118.19704507406;32188.19529511781;24325.880052798544;24390.243902439306;24686.382840429134;24774.31846421718;24986.250412487654 15:26:43 INFO - PROCESS | 3363 | |2;jquery.html;340.48756621284963;354.9396602577563;357.25690563627654;365.568629017759;382.0686430095864;13240.801387979176;15789.447369342075;16355.672886542263;17561.121943902817;17684;16751.246193921274;16788;17090.838194933945;17188.312467501284;17198.226079826407;4931.087748766472;4944.802207911688;5072.873178170538;5106.821261255856;5116.641835071547;3577.9284414311696;3781.2248489059816;5045.545900868921;5154.587632989352;5540.002799496089;5777.19119323295;5974.79088231912;5977.282726072877;5982.581219314649;5993;5737.454941780524;5829.4753472187285;5908.46823785857;5909.793157239476;5977.16319715238;5164.225366195101;5168.250603662448;5169.327987361657;5170.948290517084;5173.689578625294;20115.79884201154;20157.697634535492;20237.898810505852;20342.898285508476;20406.591868162555;1831.8717689761722;2023.2311721545866;2098.150249149082;2362.303120579416;2433;8483.618237179342;12142.332171730552;12892.935535322264;13424.798628020588;13470.730585388237;1635.255958538862;1648.934042638293;1690.697685969299;1743.738439234125;1769.1684908093175;62498;63430.048549271334;64624.676876615784;64755.67622161906;64988.3501164987;7638.350740187147;8108.797280068045;8168.550729709804;8209.302209312156;9796.559154838049;10502.159827213805;10515.789684206273;10522.210834187466;10526.631567895087;10545.736356590993;5930.851728706817;6020.187274717857;6158.692065396713;6278.434940855345;6390.712417941204;5772.336181339199;5779.162021506858;5948.524118070544;5985.311689155803;6136.509079273647;3282.688144626257;3288.32589319189;3322.591121436939;3370.342783157261;3376.7636265461183;1674.9748753768704;1680.9746054906498;1697.8471937525683;1706.1725063344268;1708.2398332741811;809.5871105736117;814.6456291513092;864.92215700586;885.6900084970209;891.4963045879058;16347.591310217102;16838.494845154666;17019.48941531756;17358.479245622653;17762.200700968486;797.3740613618281;812.9187081291942;848.6478111583624;853.9573021348908;878.9912100879037;9009.666423423245;9032.63869445228;9776.071273229032;9961.252906032076;9972.351797133164;349774;350867;359246;360662;362144;75301.62349188329;76214.23785762182;76496.61751691317;76952.23047769563;78316.60841695755;858.3091310119055;880.5509190312858;906.4878343735768;908.4957848394073;911.1799380557551;14941.402343906557;14982.561135111708;15546.533603991898;15598.688026239639;15824.60438489047;37411.81294093621;38220.2355952885;38291.808540957114;38847.223055539296;39541.60458395494;51030.48969510257;51759.74120129375;52335;52606;52699.736501317246;7233.746818861525;7245.746398876014;7290.671919763729;7454.552726836408;7455.6644950978425;8414.848444119145;11569.88430115688;11731.296122232694;12169.756604868031;12311.75376492483;9677.951610241904;9856.112949834262;10032.347897386646;10169.491525423848;10631;9819.656312029045;9896.10935015852;9901.752456188653;10179.541920613738;10304.536295866554;9714.125728684452;10130.493475326057;10373.948130259301;10629.626299859077;10782.08352290064;7256.673449694671;7364.7790566280955;7448.180700123091;7618.200088990576;7689.038870141232;9413.905860941577;10496.737581560523;10540.947295263475;10682.519286632276;10775.191860610328 15:26:43 INFO - PROCESS | 3363 | |3;mootools.html;1207.4687137659434;1317.9670508237293;1357.4095268558176;1377.3182274773983;1640.49964760748;5963.344032156465;6566.507511936606;6799.01414294927;6812.931870681298;6950.339717726808;6766.3571960663785;6822.522423430361;6828;6833.180018397799;6839.65801709915;1783.384732267372;1850.3246315095028;2016.8689035212738;2049.0574335805545;2049.651559234926;1972.36884197057;2035.3792093411503;2035.7658869230006;2042.7140200371884;2043;1981.6631172700675;1992.5915187386624;1999.5401057756724;2003.2988454041042;2005.4685508340228;2017.939461816148;2031.1570698160247;2036.4806974221478;2038.398672391648;2039.479932617173;2024.7772744998038;2028.4827369020952;2049.825764809993;2064.917403303866;2068.75174979002;6762.087118239024;6897.689603967833;6909.723611055551;6911.688973996132;6912.205096413926;3967.940480892789;4086.734362266473;4112.650424713903;4120.896977575555;4122.793860307002;2591.7667409933006;2744.3276397282793;2786.986065069682;2801.887924483018;2802.607634931111;813.6501304439089;845.7885528617845;865.5239618210022;878.7715194049529;882.7660669922476;9010.59452324647;9022.413543119676;9048.502332371718;9069.319801014884;9073.09269073094;3614.963850361463;3760.5487341519192;3776;3816.656500914931;3824.9426258606145;7683.423743219281;7686.769396918102;7687.654055567513;7688.269614386624;7702.229777022253;4145.958540414617;4152.5432202457705;4152.688548358885;4156.272652285898;4162;3236;3370.1743072947284;3619.9638003620153;3799.544054713452;3804.847806087781;3212.662670419619;3248.528963300308;3364.4616861302075;3370.898873033813;3381.3406385754542;1057.8310966382037;1079.1420820447763;1084.6312253833735;1088.548252475214;1089.760542086005;745.9328660420588;767.1791183433672;826.8263664630375;829.618375547255;829.7261903571807;4475.776211189427;4624.6993945394115;4761.095391875532;4766.523347665206;4913.680610760289;839.6179738219096;868.4398562926876;903.4714691905309;914.1544071733753;936.5972631768269;3363.7813542119684;3464.2725027744455;3468.358353704573;3498.3353162898966;3584.856605735741;7968.721094761656;8028.317593004544;8033.317168040782;8036.879446808302;8076.434649574591;7978.880316795253;8019.478733882279;8071.556064416396;8080;8080.2727754502375;6091.086337049478;6342.651154186564;6348.174737284124;6485.059666348353;6491.9350806492275;4659.93010104842;4790.904181916272;4817.0365926814075;4884.4871288515615;5192.766325515286;7363.116426028911;7578.962105189439;7648.388128949782;7655.349295309961;7656.73201437947;7617.390608751176;7729.806754831175;7806.102298235548;7808.9609551954145;7815.062192536705;5792.0153573893385;5798.304203495608;5838.620489668158;5876.030454974882;5887.793927212527;6305.054241863757;6391.360863913572;6394.616323020634;6397.776077837253;6398.5521013528605;5903.91144132847;5982;6021.7591296347655;6146.907796383147;6163.96918015407;5827.155062515827;5858.47273745348;5880.764769409347;5885.529157667291;5893.2044174035955;5385.46145385474;5386.13821788512;5389.8922021558565;5394.056040193029;5426.755795989269;4036.656884164879;4051.3517837145914;4188.554948542748;4188.832446702219;4189.853355132555;3946.684265258715;4034.9193016138925;4057.229126465962;4064.1262128642484;4190.413342132043 15:26:43 INFO - PROCESS | 3363 | |4;prototype.html;301.7148794389303;301.7178937693257;302.8864175934023;308.23711314496614;308.96446908605515;4718.433787945447;4847.8545643630705;4866.85399438017;4902.779374928128;4913.238448040551;4816.446108697501;4817.542333478323;4817.662763606549;4819.662623616348;4844.152273352167;3533.7879727216323;3642.5993140754495;3648.908777280563;3731.664150226486;3738.1402277476195;3708.830334254451;3716.5540135183815;3722.460243264725;3727.2172843702856;3735.4957080794015;3689.7970611616483;3698.4637227601984;3705.7961812100325;3708.369577171887;3716.3682174030346;3547.8935631931085;3565.322588708137;3602.4596310553366;3684.410494320909;3705.184859330943;3179.60254968129;3194.8242846643425;3197.76016798741;3214.5660335854595;3224.596925384327;4670.696404733681;4672.95327046732;4673.929891051637;4684;4695.835645752416;4189.832406703728;4191.643710284629;4209;4226.661867050629;4232.703710740249;4887.755612219375;4964.851054468371;5001.324821149135;5003.899922001576;5006.173981293082;3444.2422667013216;3467.2372078142766;3471.75697701161;3481.9825900870587;3485.442329227337;5210.478952104797;5226.947730522722;5243.973780131113;5300.734963251861;5316.840494785162;3397.184675677869;3552.928941421157;3553.116848070527;3577.463380492895;3582.3521180256853;3613.6205698401814;3626.3291291111227;3634.4548317752547;3637.108908317426;3643.289558536113;4201.096764195712;4205.747655140702;4214.641755450821;4222.9577704223175;4231.788410579458;4849.80600775965;5030.471800460972;5057.468965758616;5077.796888124508;5084.262781896604;1200.7812070748023;1204.1931905623253;1210.2496452199694;1214.9733475213432;1215.3254943506438;787.912680500905;808;811.3955103447969;816.5468165168388;821.9054808697076;4292.163028209469;4313.202057619351;4319.200947824663;4330.220560299176;4407.449068866392;5057.696538207646;5207.3490813648295;5211.400688920747;5216.895946911582;5218.869528261824;4471.195184866755;4482.820687172477;4490.461144662661;4490.887727806766;4491.708038977456;2626.615507880353;2643.5638119710034;2660.93347666306;2677.6251324814543;2693.878775455109;4672.322513235562;4690.249560070441;4695.577398034194;4699.872030712672;4707.8587642370785;5170.285600059999;5177.844664660066;5183;5202.439268219519;5223.537409525339;4533.144119204946;4693.22561777303;4721.126591580569;4731.4558825735485;4737.360456338419;4997.250412438163;5037.848864534069;5055.747212639427;5056.064628043824;5074.289599456153;4916.213405855046;4924.596490000333;4947.431045429822;4961.776720047535;4965.950340496694;4521.6608754344215;4671.556202160789;4682.461516925598;4696.553827386393;4698.765061746967;3454.222799870009;3460.273342597982;3467.878624248139;3467.94798078024;3470.965290347064;5010.549050585465;5029.899402012013;5089.160288552347;5098.46466121052;5115.18157094863;4697;4862.41651001882;4951.752412379438;4998.1253280676465;4999.400311837897;4832.710037397767;4836.540528649773;4847.975760121177;4863.786976925561;4872.317875497362;4792.400949881265;4814.350062741485;4898.975505122452;4909.705417674951;4916.852494425173;4675.906481870412;4923.729194894172;4947.431045429822;4956.950430495793;4982.75086245679;4785.330053792402;4799.688020278671;4812.663113582015;4837.975810120927;4839.443464001685 15:26:43 INFO - PROCESS | 3363 | |5;yui.html;593.3562085137626;642.2068745099798;652.1782553981983;699.6711545573581;736.8820988641818;12485.625431237066;13227.669308267274;13252.93373533131;13266.867331326685;13274.734505309889;12856.100072994894;12895.355232238375;12911.483540658386;12935.288559129243;12972.481100755982;598.2312728144313;616.8889599872022;727.7925791149523;733.7248531800561;747.2751431111813;744.4677055905026;757.7688804914499;759.2028370211284;759.6012093650822;760.817403823084;807.5477732469835;809.4374409785202;809.4495742894829;809.7773112394079;810.05628442864;624.665803794972;644.5294934697697;789.5736302396699;804.1395706593947;805.472415567804;583.4107551373102;588.0855270055063;588.2470437839572;588.5850475414823;589.1016200294542;12321;12327.260364378077;12328.32194229317;12343.19769214998;12406.317652529107;13899.305034748324;14092.506762263272;14105.858941410557;14192.574222773334;14205.502807401692;10411.947940260327;10783.13734901206;10885.945570272177;10993.890061099446;11336.43317834105;3204.3110731192905;3231.4344989626957;3274.3287626036604;3339.2987472630775;3398.4392575225056;62062;70346;71498.64250678713;71519.99999999948;73039;10910.781784364268;10969.561217551369;11000.944995274973;11017.559297628166;11244;21107.894460527907;21322.893385533283;21350.893245533673;21580.028898699595;21581;6573.441257493071;6622.91228873908;6680.766173183915;6719.496037797185;6960.37356637905;3271.9182020449202;3527.2416430467574;3635.709143268585;3643.744937854378;3662.4689420033947;2712.5931110333213;2806.242314575053;2829.122971878724;2832.9858350708114;2840;438.97146685465344;439.5165318150009;442.09370789880614;444.74649449813944;444.9644028477714;582.2169182449599;596.6837576084682;597.3011576455557;612.1900476811368;612.451855589254;7463.328300452985;7955.960220198977;8102.716404925799;8774.780630484162;8881.555922203865;504.6820503083034;556.2129586634948;589.802416190581;601.3505414152704;608.4888693497484;3776.527934008249;3944.36890097583;4000.299947509174;4057.756534607874;4105.8973525662095;65710;66602.66698666476;67426.32573674299;69514;69706.37789409998;57586;58186.709066454394;58335;58816.41183588195;58853.41146588565;53544.46455535472;53842;54490.45509544854;54794.72602637041;55232.17151742727;7837.7648670539975;7921.405894557816;7931.5637639930355;8141.470804397695;8156.1744603332845;35541.28917421689;35848.59438353352;35980.64019359773;36342.818285908405;37793.81103094467;38186.98073404505;45007.324890126016;45533.31700024436;51546.484535155665;52467.47532524626;10726.839097413702;10885.074768644754;10981.890181098086;11251.943740281245;12217.144799863925;12460.190087644274;12622.179558328678;12803.359832008176;12954.417051232906;13450.932745336602;11747.236429632047;11760.176787624785;12161.391930403268;12385.19496232742;12490.500379984698;11384.715382115513;11696.47365868555;11812.881871181178;12119;12406.76552087167;11073.224874258725;11154.386508742096;11316.15128865322;11544.307341559534;11568;9871.654492092743;9973.700788976343;10241.692749217535;10565.366078035342;10639;9878.802423951625;10404.167666586807;10428;10509.474526273809;10538.57845686164 15:26:43 INFO - PROCESS | 3363 | __end_tp_report 15:26:43 INFO - PROCESS | 3363 | __start_cc_report 15:26:43 INFO - PROCESS | 3363 | _x_x_mozilla_cycle_collect,1853 15:26:43 INFO - PROCESS | 3363 | __end_cc_report 15:26:43 INFO - PROCESS | 3363 | __startTimestamp1480634803335__endTimestamp 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | ------- Summary: start ------- 15:26:43 INFO - PROCESS | 3363 | Number of tests: 6 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#0] dojo.html Cycles:165 Average:4627.04 Median:4713.41 stddev:476.83 (10.1%) stddev-sans-first:450.05 15:26:43 INFO - PROCESS | 3363 | Values: 2565.9 2590.1 2601.8 2603.6 2612.2 4577.3 4624.9 4710.6 4720.5 4728.6 4651.2 4677.3 4683.6 4703.9 4751.2 4305.4 4310.6 4357.7 4372.3 4385.6 4325.6 4372.9 4379.7 4413.0 4436.0 4404.8 4474.8 4478.7 4485.9 4495.8 4234.5 4249.9 4262.6 4265.2 4369.4 4288.5 4289.8 4289.9 4339.8 4341.8 4664.7 4676.1 4694.2 4712.2 4713.4 4839.6 4874.0 5017.4 5033.6 5045.2 4966.6 4985.8 5049.5 5054.1 5085.6 4673.3 4679.2 4682.2 4700.9 4728.1 5116.2 5126.0 5127.1 5133.4 5177.5 4827.7 4843.1 4854.0 4854.1 4869.1 4960.8 4961.9 4964.5 4991.6 5008.9 4899.3 4905.9 4906.1 4960.6 4968.9 4690.0 4699.7 4732.8 4893.9 4902.3 4212.5 4221.9 4247.6 4255.0 4307.2 3716.6 3728.8 3730.0 3754.6 3756.7 4175.5 4325.3 4335.7 4339.0 4378.1 4880.9 4959.2 4979.8 5018.3 5023.8 4331.5 4335.8 4335.9 4376.2 4380.0 4557.4 4563.6 4569.5 4573.0 4612.3 4551.2 4576.1 4580.7 4621.9 4692.1 5010.3 5076.1 5079.9 5125.7 5138.3 4704.7 4714.1 4715.2 4727.3 4755.3 4781.9 4853.8 5002.1 5005.1 5043.1 4939.8 4944.5 4960.1 5030.4 5088.2 4881.0 4897.1 4898.6 4914.4 4948.7 4716.2 4890.4 4892.0 4894.1 4937.7 4684.2 4830.2 4859.8 4865.4 4943.4 4987.1 5004.8 5007.5 5008.1 5053.7 4645.3 4659.1 4659.5 4821.3 4878.7 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#1] ext.html Cycles:180 Average:45459.02 Median:365156.00 stddev:80687.81 (22.1%) stddev-sans-first:80843.76 15:26:43 INFO - PROCESS | 3363 | Values: 566.1 569.8 570.4 579.4 581.7 28325.0 30674.0 37132.8 38238.4 38749.6 39335.6 39686.6 39755.2 39799.8 39817.4 7522.8 7676.2 7680.8 7683.6 7687.2 7400.8 7595.0 9604.1 9890.7 9898.0 9910.3 9968.6 9983.0 9984.5 9987.6 9800.1 9823.5 9851.9 9860.8 9872.5 9606.2 9672.1 9673.3 9673.3 9675.4 37539.6 37798.0 37972.0 38010.8 38028.0 2899.5 3070.1 3113.7 3250.9 3328.0 27891.4 28320.6 28367.0 28836.4 28938.0 3389.0 3444.6 3452.2 3542.5 3601.5 254495.0 254656.0 254765.0 254851.0 254969.0 50887.0 51266.7 51313.2 51339.0 51384.0 53375.2 53396.0 53411.5 53519.7 53721.5 12090.2 12414.6 12415.0 12418.6 12456.1 7564.1 7661.6 8670.5 8677.5 8717.9 13559.3 14652.8 14831.3 15059.5 15664.7 6091.2 6111.7 6214.4 6257.2 6258.3 791.5 848.1 916.8 923.3 937.0 27473.9 27548.7 27884.3 28008.3 28053.2 898.1 900.9 917.4 923.4 932.0 13770.4 14593.6 14630.9 14750.5 14853.3 360945.0 364227.0 364774.0 364832.0 365480.0 47665.0 47729.5 47788.5 47861.3 47862.3 1028.2 1078.3 1109.2 1136.6 1161.0 24883.8 27787.0 28708.3 28989.9 29297.4 38396.6 38905.0 38930.2 38939.8 38944.6 291019.0 292528.0 292851.0 292858.0 293295.0 23097.9 24151.5 24182.5 24876.3 25047.9 39775.6 40248.8 40269.4 41143.8 41174.8 34409.0 34546.0 34698.7 34973.5 35327.3 34380.7 34383.7 34685.8 34721.8 34759.8 34099.5 34661.7 34940.8 35057.8 35720.8 29850.7 32031.7 32074.4 32118.2 32188.2 24325.9 24390.2 24686.4 24774.3 24986.3 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#2] jquery.html Cycles:180 Average:23098.67 Median:5059.21 stddev:59071.44 (1167.6%) stddev-sans-first:59212.44 15:26:43 INFO - PROCESS | 3363 | Values: 340.5 354.9 357.3 365.6 382.1 13240.8 15789.4 16355.7 17561.1 17684.0 16751.2 16788.0 17090.8 17188.3 17198.2 4931.1 4944.8 5072.9 5106.8 5116.6 3577.9 3781.2 5045.5 5154.6 5540.0 5777.2 5974.8 5977.3 5982.6 5993.0 5737.5 5829.5 5908.5 5909.8 5977.2 5164.2 5168.3 5169.3 5170.9 5173.7 20115.8 20157.7 20237.9 20342.9 20406.6 1831.9 2023.2 2098.2 2362.3 2433.0 8483.6 12142.3 12892.9 13424.8 13470.7 1635.3 1648.9 1690.7 1743.7 1769.2 62498.0 63430.0 64624.7 64755.7 64988.4 7638.4 8108.8 8168.6 8209.3 9796.6 10502.2 10515.8 10522.2 10526.6 10545.7 5930.9 6020.2 6158.7 6278.4 6390.7 5772.3 5779.2 5948.5 5985.3 6136.5 3282.7 3288.3 3322.6 3370.3 3376.8 1675.0 1681.0 1697.8 1706.2 1708.2 809.6 814.6 864.9 885.7 891.5 16347.6 16838.5 17019.5 17358.5 17762.2 797.4 812.9 848.6 854.0 879.0 9009.7 9032.6 9776.1 9961.3 9972.4 349774.0 350867.0 359246.0 360662.0 362144.0 75301.6 76214.2 76496.6 76952.2 78316.6 858.3 880.6 906.5 908.5 911.2 14941.4 14982.6 15546.5 15598.7 15824.6 37411.8 38220.2 38291.8 38847.2 39541.6 51030.5 51759.7 52335.0 52606.0 52699.7 7233.7 7245.7 7290.7 7454.6 7455.7 8414.8 11569.9 11731.3 12169.8 12311.8 9678.0 9856.1 10032.3 10169.5 10631.0 9819.7 9896.1 9901.8 10179.5 10304.5 9714.1 10130.5 10373.9 10629.6 10782.1 7256.7 7364.8 7448.2 7618.2 7689.0 9413.9 10496.7 10540.9 10682.5 10775.2 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#3] mootools.html Cycles:180 Average:4507.34 Median:5385.80 stddev:2397.54 (44.5%) stddev-sans-first:2391.44 15:26:43 INFO - PROCESS | 3363 | Values: 1207.5 1318.0 1357.4 1377.3 1640.5 5963.3 6566.5 6799.0 6812.9 6950.3 6766.4 6822.5 6828.0 6833.2 6839.7 1783.4 1850.3 2016.9 2049.1 2049.7 1972.4 2035.4 2035.8 2042.7 2043.0 1981.7 1992.6 1999.5 2003.3 2005.5 2017.9 2031.2 2036.5 2038.4 2039.5 2024.8 2028.5 2049.8 2064.9 2068.8 6762.1 6897.7 6909.7 6911.7 6912.2 3967.9 4086.7 4112.7 4120.9 4122.8 2591.8 2744.3 2787.0 2801.9 2802.6 813.7 845.8 865.5 878.8 882.8 9010.6 9022.4 9048.5 9069.3 9073.1 3615.0 3760.5 3776.0 3816.7 3824.9 7683.4 7686.8 7687.7 7688.3 7702.2 4146.0 4152.5 4152.7 4156.3 4162.0 3236.0 3370.2 3620.0 3799.5 3804.8 3212.7 3248.5 3364.5 3370.9 3381.3 1057.8 1079.1 1084.6 1088.5 1089.8 745.9 767.2 826.8 829.6 829.7 4475.8 4624.7 4761.1 4766.5 4913.7 839.6 868.4 903.5 914.2 936.6 3363.8 3464.3 3468.4 3498.3 3584.9 7968.7 8028.3 8033.3 8036.9 8076.4 7978.9 8019.5 8071.6 8080.0 8080.3 6091.1 6342.7 6348.2 6485.1 6491.9 4659.9 4790.9 4817.0 4884.5 5192.8 7363.1 7579.0 7648.4 7655.3 7656.7 7617.4 7729.8 7806.1 7809.0 7815.1 5792.0 5798.3 5838.6 5876.0 5887.8 6305.1 6391.4 6394.6 6397.8 6398.6 5903.9 5982.0 6021.8 6146.9 6164.0 5827.2 5858.5 5880.8 5885.5 5893.2 5385.5 5386.1 5389.9 5394.1 5426.8 4036.7 4051.4 4188.6 4188.8 4189.9 3946.7 4034.9 4057.2 4064.1 4190.4 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#4] prototype.html Cycles:180 Average:4100.82 Median:4691.74 stddev:1205.92 (25.7%) stddev-sans-first:1175.11 15:26:43 INFO - PROCESS | 3363 | Values: 301.7 301.7 302.9 308.2 309.0 4718.4 4847.9 4866.9 4902.8 4913.2 4816.4 4817.5 4817.7 4819.7 4844.2 3533.8 3642.6 3648.9 3731.7 3738.1 3708.8 3716.6 3722.5 3727.2 3735.5 3689.8 3698.5 3705.8 3708.4 3716.4 3547.9 3565.3 3602.5 3684.4 3705.2 3179.6 3194.8 3197.8 3214.6 3224.6 4670.7 4673.0 4673.9 4684.0 4695.8 4189.8 4191.6 4209.0 4226.7 4232.7 4887.8 4964.9 5001.3 5003.9 5006.2 3444.2 3467.2 3471.8 3482.0 3485.4 5210.5 5226.9 5244.0 5300.7 5316.8 3397.2 3552.9 3553.1 3577.5 3582.4 3613.6 3626.3 3634.5 3637.1 3643.3 4201.1 4205.7 4214.6 4223.0 4231.8 4849.8 5030.5 5057.5 5077.8 5084.3 1200.8 1204.2 1210.2 1215.0 1215.3 787.9 808.0 811.4 816.5 821.9 4292.2 4313.2 4319.2 4330.2 4407.4 5057.7 5207.3 5211.4 5216.9 5218.9 4471.2 4482.8 4490.5 4490.9 4491.7 2626.6 2643.6 2660.9 2677.6 2693.9 4672.3 4690.2 4695.6 4699.9 4707.9 5170.3 5177.8 5183.0 5202.4 5223.5 4533.1 4693.2 4721.1 4731.5 4737.4 4997.3 5037.8 5055.7 5056.1 5074.3 4916.2 4924.6 4947.4 4961.8 4966.0 4521.7 4671.6 4682.5 4696.6 4698.8 3454.2 3460.3 3467.9 3467.9 3471.0 5010.5 5029.9 5089.2 5098.5 5115.2 4697.0 4862.4 4951.8 4998.1 4999.4 4832.7 4836.5 4848.0 4863.8 4872.3 4792.4 4814.4 4899.0 4909.7 4916.9 4675.9 4923.7 4947.4 4957.0 4982.8 4785.3 4799.7 4812.7 4838.0 4839.4 15:26:43 INFO - PROCESS | 3363 | 15:26:43 INFO - PROCESS | 3363 | [#5] yui.html Cycles:180 Average:15312.50 Median:4029.03 stddev:19376.31 (480.9%) stddev-sans-first:19399.14 15:26:43 INFO - PROCESS | 3363 | Values: 593.4 642.2 652.2 699.7 736.9 12485.6 13227.7 13252.9 13266.9 13274.7 12856.1 12895.4 12911.5 12935.3 12972.5 598.2 616.9 727.8 733.7 747.3 744.5 757.8 759.2 759.6 760.8 807.5 809.4 809.4 809.8 810.1 624.7 644.5 789.6 804.1 805.5 583.4 588.1 588.2 588.6 589.1 12321.0 12327.3 12328.3 12343.2 12406.3 13899.3 14092.5 14105.9 14192.6 14205.5 10411.9 10783.1 10885.9 10993.9 11336.4 3204.3 3231.4 3274.3 3339.3 3398.4 62062.0 70346.0 71498.6 71520.0 73039.0 10910.8 10969.6 11000.9 11017.6 11244.0 21107.9 21322.9 21350.9 21580.0 21581.0 6573.4 6622.9 6680.8 6719.5 6960.4 3271.9 3527.2 3635.7 3643.7 3662.5 2712.6 2806.2 2829.1 2833.0 2840.0 439.0 439.5 442.1 444.7 445.0 582.2 596.7 597.3 612.2 612.5 7463.3 7956.0 8102.7 8774.8 8881.6 504.7 556.2 589.8 601.4 608.5 3776.5 3944.4 4000.3 4057.8 4105.9 65710.0 66602.7 67426.3 69514.0 69706.4 57586.0 58186.7 58335.0 58816.4 58853.4 53544.5 53842.0 54490.5 54794.7 55232.2 7837.8 7921.4 7931.6 8141.5 8156.2 35541.3 35848.6 35980.6 36342.8 37793.8 38187.0 45007.3 45533.3 51546.5 52467.5 10726.8 10885.1 10981.9 11251.9 12217.1 12460.2 12622.2 12803.4 12954.4 13450.9 11747.2 11760.2 12161.4 12385.2 12490.5 11384.7 11696.5 11812.9 12119.0 12406.8 11073.2 11154.4 11316.2 11544.3 11568.0 9871.7 9973.7 10241.7 10565.4 10639.0 9878.8 10404.2 10428.0 10509.5 10538.6 15:26:43 INFO - PROCESS | 3363 | -------- Summary: end -------- 15:26:43 INFO - PROCESS | 3363 | 15:26:44 INFO - TEST-INFO | 3363: exit 0 15:26:44 INFO - TEST-OK | dromaeo_css | took 1124024ms 15:26:44 INFO - TEST-START | kraken 15:26:44 INFO - operating with platform_type : linux_ 15:26:44 INFO - Initialising browser for kraken test... 15:26:44 INFO - TEST-INFO | started process 3664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9NB5_/profile http://localhost:46164/getInfo.html) 15:26:48 INFO - PROCESS | 3664 | __metrics Screen width/height:1600/1200 15:26:48 INFO - PROCESS | 3664 | colorDepth:24 15:26:48 INFO - PROCESS | 3664 | Browser inner width/height: 1024/697 15:26:48 INFO - PROCESS | 3664 | __metrics 15:26:49 INFO - PROCESS | 3664 | 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" 15:26:49 INFO - PROCESS | 3664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:26:50 INFO - PROCESS | 3664 | console.error: PushService: 15:26:50 INFO - PROCESS | 3664 | stateChangeProcessEnqueue: Error transitioning state 15:26:50 INFO - PROCESS | 3664 | UnknownError 15:26:50 INFO - TEST-INFO | 3664: exit 0 15:26:50 INFO - Browser initialized. 15:26:50 INFO - Running cycle 1/1 for kraken test... 15:26:50 INFO - TEST-INFO | started process 3747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9NB5_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:03 INFO - PROCESS | 3747 | RSS: Main: 175357952 15:27:03 INFO - PROCESS | 3747 | 15:27:05 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46164/tests/kraken/driver.html?testName=audio-beat-detection) 15:27:06 INFO - PROCESS | 3747 | RSS: Main: 236158976 15:27:06 INFO - PROCESS | 3747 | 15:27:09 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46164/tests/kraken/driver.html?testName=audio-dft) 15:27:10 INFO - PROCESS | 3747 | RSS: Main: 218009600 15:27:10 INFO - PROCESS | 3747 | 15:27:13 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46164/tests/kraken/driver.html?testName=audio-fft) 15:27:14 INFO - PROCESS | 3747 | RSS: Main: 300380160 15:27:14 INFO - PROCESS | 3747 | 15:27:16 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46164/tests/kraken/driver.html?testName=audio-oscillator) 15:27:16 INFO - PROCESS | 3747 | RSS: Main: 211509248 15:27:16 INFO - PROCESS | 3747 | 15:27:19 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46164/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:27:19 INFO - PROCESS | 3747 | RSS: Main: 214810624 15:27:19 INFO - PROCESS | 3747 | 15:27:22 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46164/tests/kraken/driver.html?testName=imaging-darkroom) 15:27:23 INFO - PROCESS | 3747 | RSS: Main: 254488576 15:27:23 INFO - PROCESS | 3747 | 15:27:26 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46164/tests/kraken/driver.html?testName=imaging-desaturate) 15:27:27 INFO - PROCESS | 3747 | RSS: Main: 247971840 15:27:27 INFO - PROCESS | 3747 | 15:27:30 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46164/tests/kraken/driver.html?testName=json-parse-financial) 15:27:31 INFO - PROCESS | 3747 | RSS: Main: 250163200 15:27:31 INFO - PROCESS | 3747 | 15:27:32 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46164/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:27:33 INFO - PROCESS | 3747 | RSS: Main: 213581824 15:27:33 INFO - PROCESS | 3747 | 15:27:35 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:27:36 INFO - PROCESS | 3747 | RSS: Main: 221458432 15:27:36 INFO - PROCESS | 3747 | 15:27:38 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:27:38 INFO - PROCESS | 3747 | RSS: Main: 229539840 15:27:38 INFO - PROCESS | 3747 | 15:27:42 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:27:42 INFO - PROCESS | 3747 | RSS: Main: 238391296 15:27:42 INFO - PROCESS | 3747 | 15:27:45 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:27:46 INFO - PROCESS | 3747 | RSS: Main: 234651648 15:27:46 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | Cycle 1(1): loaded http://localhost:46164/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46164/tests/kraken/driver.html?testName=ai-astar) 15:27:48 INFO - PROCESS | 3747 | RSS: Main: 287895552 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | __start_tp_report 15:27:48 INFO - PROCESS | 3747 | _x_x_mozilla_page_load 15:27:48 INFO - PROCESS | 3747 | _x_x_mozilla_page_load_details 15:27:48 INFO - PROCESS | 3747 | |i|pagename|runs| 15:27:48 INFO - PROCESS | 3747 | |0;ai-astar;115;117;118;118;117;119;118;117;119;116 15:27:48 INFO - PROCESS | 3747 | |1;audio-beat-detection;166;128;130;184;132;131;136;129;156;131 15:27:48 INFO - PROCESS | 3747 | |2;audio-dft;162;162;160;160;160;162;161;161;159;159 15:27:48 INFO - PROCESS | 3747 | |3;audio-fft;77;75;76;106;77;78;77;78;91;77 15:27:48 INFO - PROCESS | 3747 | |4;audio-oscillator;83;85;107;84;84;82;109;84;84;113 15:27:48 INFO - PROCESS | 3747 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;118 15:27:48 INFO - PROCESS | 3747 | |6;imaging-darkroom;146;147;147;147;148;148;147;148;146;155 15:27:48 INFO - PROCESS | 3747 | |7;imaging-desaturate;96;95;95;95;96;96;96;95;95;95 15:27:48 INFO - PROCESS | 3747 | |8;json-parse-financial;66;65;63;63;64;66;70;64;64;64 15:27:48 INFO - PROCESS | 3747 | |9;json-stringify-tinderbox;66;66;67;66;67;67;67;67;67;67 15:27:48 INFO - PROCESS | 3747 | |10;stanford-crypto-aes;94;88;97;86;95;93;86;92;86;86 15:27:48 INFO - PROCESS | 3747 | |11;stanford-crypto-ccm;144;141;141;146;156;142;147;141;186;145 15:27:48 INFO - PROCESS | 3747 | |12;stanford-crypto-pbkdf2;178;177;179;181;179;179;178;179;184;178 15:27:48 INFO - PROCESS | 3747 | |13;stanford-crypto-sha256-iterative;73;71;70;70;73;70;72;71;70;70 15:27:48 INFO - PROCESS | 3747 | __end_tp_report 15:27:48 INFO - PROCESS | 3747 | __start_cc_report 15:27:48 INFO - PROCESS | 3747 | _x_x_mozilla_cycle_collect,1992 15:27:48 INFO - PROCESS | 3747 | __end_cc_report 15:27:48 INFO - PROCESS | 3747 | __startTimestamp1480634868678__endTimestamp 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | ------- Summary: start ------- 15:27:48 INFO - PROCESS | 3747 | Number of tests: 14 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#0] ai-astar Cycles:10 Average:117.40 Median:118.00 stddev:1.26 (1.1%) stddev-sans-first:1.00 15:27:48 INFO - PROCESS | 3747 | Values: 115.0 117.0 118.0 118.0 117.0 119.0 118.0 117.0 119.0 116.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:134.00 stddev:19.50 (14.6%) stddev-sans-first:18.70 15:27:48 INFO - PROCESS | 3747 | Values: 166.0 128.0 130.0 184.0 132.0 131.0 136.0 129.0 156.0 131.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 15:27:48 INFO - PROCESS | 3747 | Values: 162.0 162.0 160.0 160.0 160.0 162.0 161.0 161.0 159.0 159.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#3] audio-fft Cycles:10 Average:81.20 Median:77.00 stddev:9.82 (12.8%) stddev-sans-first:10.30 15:27:48 INFO - PROCESS | 3747 | Values: 77.0 75.0 76.0 106.0 77.0 78.0 77.0 78.0 91.0 77.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:84.00 stddev:12.64 (15.1%) stddev-sans-first:13.03 15:27:48 INFO - PROCESS | 3747 | Values: 83.0 85.0 107.0 84.0 84.0 82.0 109.0 84.0 84.0 113.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:27:48 INFO - PROCESS | 3747 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:147.50 stddev:2.60 (1.8%) stddev-sans-first:2.67 15:27:48 INFO - PROCESS | 3747 | Values: 146.0 147.0 147.0 147.0 148.0 148.0 147.0 148.0 146.0 155.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:27:48 INFO - PROCESS | 3747 | Values: 96.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:2.08 (3.2%) stddev-sans-first:2.17 15:27:48 INFO - PROCESS | 3747 | Values: 66.0 65.0 63.0 63.0 64.0 66.0 70.0 64.0 64.0 64.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 15:27:48 INFO - PROCESS | 3747 | Values: 66.0 66.0 67.0 66.0 67.0 67.0 67.0 67.0 67.0 67.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:92.50 stddev:4.35 (4.7%) stddev-sans-first:4.40 15:27:48 INFO - PROCESS | 3747 | Values: 94.0 88.0 97.0 86.0 95.0 93.0 86.0 92.0 86.0 86.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:145.50 stddev:13.80 (9.5%) stddev-sans-first:14.52 15:27:48 INFO - PROCESS | 3747 | Values: 144.0 141.0 141.0 146.0 156.0 142.0 147.0 141.0 186.0 145.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:179.00 stddev:1.99 (1.1%) stddev-sans-first:2.06 15:27:48 INFO - PROCESS | 3747 | Values: 178.0 177.0 179.0 181.0 179.0 179.0 178.0 179.0 184.0 178.0 15:27:48 INFO - PROCESS | 3747 | 15:27:48 INFO - PROCESS | 3747 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:1.25 (1.8%) stddev-sans-first:1.09 15:27:48 INFO - PROCESS | 3747 | Values: 73.0 71.0 70.0 70.0 73.0 70.0 72.0 71.0 70.0 70.0 15:27:48 INFO - PROCESS | 3747 | -------- Summary: end -------- 15:27:48 INFO - PROCESS | 3747 | 15:27:49 INFO - TEST-INFO | 3747: exit 0 15:27:50 INFO - TEST-OK | kraken | took 65692ms 15:27:50 INFO - SUITE-END | took 1189s 15:27:50 INFO - Completed test suite (00:19:50) 15:27:50 INFO - javascript benchmark 15:27:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2565.9101931432397, 2590.1193594177976, 2601.791856651467, 2603.635491031256, 2612.2424496895887, 4577.29051996941, 4624.884377890555, 4710.599599034082, 4720.527947205282, 4728.598069164121, 4651.232546629811, 4677.345171675959, 4683.601893839019, 4703.90592188156, 4751.216049351853, 4305.440292761952, 4310.612044915957, 4357.716748411359, 4372.322290045048, 4385.627221686144, 4325.589069038437, 4372.868813935587, 4379.671524635649, 4412.955870441287, 4436.0462500562535, 4404.779761011969, 4474.843380481684, 4478.686491945565, 4485.910281794362, 4495.797689103998, 4234.513031001413, 4249.851255206053, 4262.552431994627, 4265.189613973335, 4369.410129632491, 4288.485381754178, 4289.764062976535, 4289.935650965238, 4339.82640694375, 4341.848035318749, 4664.673472856902, 4676.064787042571, 4694.201985662411, 4712.151812673717, 4713.410823647044, 4839.588634966032, 4874.025194961022, 5017.37282839645, 5033.622478314104, 5045.217991211368, 4966.627502937295, 4985.825496107619, 5049.520295571915, 5054.11552978231, 5085.5931525478245, 4673.252279635276, 4679.1577516046755, 4682.157211701926, 4700.858974230779, 4728.148933192058, 5116.181410974226, 5126.000429916131, 5127.102757017507, 5133.383993920754, 5177.456367081478, 4827.662063655511, 4843.128236917319, 4853.95146048542, 4854.053459575349, 4869.050535145683, 4960.801567937314, 4961.8511444656715, 4964.528369804862, 4991.550760431579, 5008.874778130503, 4899.265110233494, 4905.9264111038365, 4906.067847109038, 4960.603151747851, 4968.90062198754, 4690.0, 4699.718016918996, 4732.834350797774, 4893.926591101136, 4902.28916807061, 4212.53662097169, 4221.894452638709, 4247.617714405718, 4254.957450425456, 4307.160103779733, 3716.627121009719, 3728.776273423549, 3730.0, 3754.6433088856515, 3756.718246131551, 4175.519815221228, 4325.329573916048, 4335.7181783184, 4339.045410009793, 4378.058717375779, 4880.902381952341, 4959.181735013754, 4979.800807967641, 5018.347614810052, 5023.8492845214705, 4331.480222373272, 4335.804888780012, 4335.913281734348, 4376.190404775127, 4379.978100109479, 4557.407537020166, 4563.566461186182, 4569.497355290981, 4573.039661671086, 4612.285095810154, 4551.158035763262, 4576.130535198302, 4580.725156490621, 4621.884452888705, 4692.061587682545, 5010.323606313101, 5076.0863044652315, 5079.898402032012, 5125.666831655931, 5138.3063286457345, 4704.67067305299, 4714.104320179155, 4715.174844402284, 4727.290906364073, 4755.334253204624, 4781.928271075867, 4853.80584776605, 5002.124628190125, 5005.1241032819835, 5043.092243396224, 4939.802407903644, 4944.456109827845, 4960.107180707508, 5030.446650868328, 5088.211327244283, 4880.975595122001, 4897.094037603055, 4898.608111351155, 4914.385701787277, 4948.653594248368, 4716.198246298069, 4890.413150421972, 4892.046051020017, 4894.094592500399, 4937.654364194472, 4684.227102528181, 4830.227163653799, 4859.757012149449, 4865.440474345354, 4943.382077240345, 4987.12725273083, 5004.824831130893, 5007.474215207497, 5008.148614735576, 5053.67151135175, 4645.3032045193495, 4659.138059459011, 4659.510751371193, 4821.252705830602, 4878.658493905393], "value": 4596.795992207696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.1168577019849, 569.8461415417837, 570.4438172781536, 579.4147910610283, 581.6742624130487, 28325.0, 30674.0, 37132.81433592835, 38238.42642360367, 38749.61250387495, 39335.606643933555, 39686.60313396865, 39755.20489590206, 39799.80100099496, 39817.40273895894, 7522.849543009136, 7676.2323767623075, 7680.846383072335, 7683.615819209045, 7687.192844751305, 7400.814979625498, 7595.0, 9604.135627793497, 9890.653827116052, 9897.95051024744, 9910.306278560503, 9968.601255949754, 9983.0, 9984.50077496126, 9987.600495980188, 9800.117989380955, 9823.459709715962, 9851.852222216672, 9860.80278394428, 9872.506374681308, 9606.231501479864, 9672.08115229052, 9673.274504412127, 9673.274504412197, 9675.419474831533, 37539.62460375416, 37798.0, 37972.0, 38010.80994595037, 38028.04929876775, 2899.5070837957596, 3070.078976307099, 3113.7041981011766, 3250.8537115829845, 3328.0, 27891.43013235992, 28320.575191372147, 28367.0, 28836.42327153445, 28938.0, 3388.9830550847337, 3444.621091679913, 3452.154222215548, 3542.468629705539, 3601.477785721082, 254495.0, 254656.0, 254765.0, 254851.0, 254969.0, 50886.982260355326, 51266.74366628218, 51313.2303015455, 51339.0, 51384.0, 53375.199372009454, 53396.0, 53411.46588534065, 53519.732401337744, 53721.46278537243, 12090.153689241668, 12414.62756117318, 12415.0, 12418.565350212699, 12456.128071035122, 7564.130125035691, 7661.61691915402, 8670.479771213748, 8677.52273624944, 8717.912820871838, 13559.322033898266, 14652.780208296885, 14831.332590033475, 15059.472918447802, 15664.710410813628, 6091.177691011745, 6111.663858487737, 6214.378562143749, 6257.217847769029, 6258.311585725566, 791.5488171742052, 848.1306660672859, 916.7983043730369, 923.2936803345447, 936.9927328122296, 27473.86263068672, 27548.724512754616, 27884.302892427448, 28008.29979250535, 28053.158405247876, 898.1070285558443, 900.9144131307515, 917.4403414473544, 923.3859483443434, 931.9953400233092, 13770.449182032808, 14593.635159121108, 14630.853691463162, 14750.483733069292, 14853.331600078023, 360945.0, 364227.0, 364774.0, 364832.0, 365480.0, 47665.0, 47729.522704773204, 47788.52211477841, 47861.28208076882, 47862.28206576904, 1028.1671845804922, 1078.2829418436809, 1109.1625822503959, 1136.6419577832955, 1160.976780464386, 24883.751162488144, 27787.0, 28708.282292942844, 28989.85505072461, 29297.41405171842, 38396.6160338393, 38905.0, 38930.221395572495, 38939.80530097331, 38944.6105538941, 291019.0, 292528.0, 292851.0, 292858.0, 293295.0, 23097.88451057734, 24151.51696966086, 24182.51634967326, 24876.253712388658, 25047.87476062608, 39775.60224397835, 40248.79875600721, 40269.39595906122, 41143.7942810284, 41174.79412602918, 34409.0, 34546.0, 34698.65301347055, 34973.47539786854, 35327.293454131286, 34380.656193437746, 34383.65616343805, 34685.826570866986, 34721.826390867885, 34759.82620086883, 34099.488507672904, 34661.65338346685, 34940.82529587336, 35057.8247108773, 35720.82139589285, 29850.701492984794, 32031.67968320287, 32074.386982278476, 32118.19704507406, 32188.19529511781, 24325.880052798544, 24390.243902439306, 24686.382840429134, 24774.31846421718, 24986.250412487654], "value": 17109.404442848576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.48756621284963, 354.9396602577563, 357.25690563627654, 365.568629017759, 382.0686430095864, 13240.801387979176, 15789.447369342075, 16355.672886542263, 17561.121943902817, 17684.0, 16751.246193921274, 16788.0, 17090.838194933945, 17188.312467501284, 17198.226079826407, 4931.087748766472, 4944.802207911688, 5072.873178170538, 5106.821261255856, 5116.641835071547, 3577.9284414311696, 3781.2248489059816, 5045.545900868921, 5154.587632989352, 5540.002799496089, 5777.19119323295, 5974.79088231912, 5977.282726072877, 5982.581219314649, 5993.0, 5737.454941780524, 5829.4753472187285, 5908.46823785857, 5909.793157239476, 5977.16319715238, 5164.225366195101, 5168.250603662448, 5169.327987361657, 5170.948290517084, 5173.689578625294, 20115.79884201154, 20157.697634535492, 20237.898810505852, 20342.898285508476, 20406.591868162555, 1831.8717689761722, 2023.2311721545866, 2098.150249149082, 2362.303120579416, 2433.0, 8483.618237179342, 12142.332171730552, 12892.935535322264, 13424.798628020588, 13470.730585388237, 1635.255958538862, 1648.934042638293, 1690.697685969299, 1743.738439234125, 1769.1684908093175, 62498.0, 63430.048549271334, 64624.676876615784, 64755.67622161906, 64988.3501164987, 7638.350740187147, 8108.797280068045, 8168.550729709804, 8209.302209312156, 9796.559154838049, 10502.159827213805, 10515.789684206273, 10522.210834187466, 10526.631567895087, 10545.736356590993, 5930.851728706817, 6020.187274717857, 6158.692065396713, 6278.434940855345, 6390.712417941204, 5772.336181339199, 5779.162021506858, 5948.524118070544, 5985.311689155803, 6136.509079273647, 3282.688144626257, 3288.32589319189, 3322.591121436939, 3370.342783157261, 3376.7636265461183, 1674.9748753768704, 1680.9746054906498, 1697.8471937525683, 1706.1725063344268, 1708.2398332741811, 809.5871105736117, 814.6456291513092, 864.92215700586, 885.6900084970209, 891.4963045879058, 16347.591310217102, 16838.494845154666, 17019.48941531756, 17358.479245622653, 17762.200700968486, 797.3740613618281, 812.9187081291942, 848.6478111583624, 853.9573021348908, 878.9912100879037, 9009.666423423245, 9032.63869445228, 9776.071273229032, 9961.252906032076, 9972.351797133164, 349774.0, 350867.0, 359246.0, 360662.0, 362144.0, 75301.62349188329, 76214.23785762182, 76496.61751691317, 76952.23047769563, 78316.60841695755, 858.3091310119055, 880.5509190312858, 906.4878343735768, 908.4957848394073, 911.1799380557551, 14941.402343906557, 14982.561135111708, 15546.533603991898, 15598.688026239639, 15824.60438489047, 37411.81294093621, 38220.2355952885, 38291.808540957114, 38847.223055539296, 39541.60458395494, 51030.48969510257, 51759.74120129375, 52335.0, 52606.0, 52699.736501317246, 7233.746818861525, 7245.746398876014, 7290.671919763729, 7454.552726836408, 7455.6644950978425, 8414.848444119145, 11569.88430115688, 11731.296122232694, 12169.756604868031, 12311.75376492483, 9677.951610241904, 9856.112949834262, 10032.347897386646, 10169.491525423848, 10631.0, 9819.656312029045, 9896.10935015852, 9901.752456188653, 10179.541920613738, 10304.536295866554, 9714.125728684452, 10130.493475326057, 10373.948130259301, 10629.626299859077, 10782.08352290064, 7256.673449694671, 7364.7790566280955, 7448.180700123091, 7618.200088990576, 7689.038870141232, 9413.905860941577, 10496.737581560523, 10540.947295263475, 10682.519286632276, 10775.191860610328], "value": 7944.740751168355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1207.4687137659434, 1317.9670508237293, 1357.4095268558176, 1377.3182274773983, 1640.49964760748, 5963.344032156465, 6566.507511936606, 6799.01414294927, 6812.931870681298, 6950.339717726808, 6766.3571960663785, 6822.522423430361, 6828.0, 6833.180018397799, 6839.65801709915, 1783.384732267372, 1850.3246315095028, 2016.8689035212738, 2049.0574335805545, 2049.651559234926, 1972.36884197057, 2035.3792093411503, 2035.7658869230006, 2042.7140200371884, 2043.0, 1981.6631172700675, 1992.5915187386624, 1999.5401057756724, 2003.2988454041042, 2005.4685508340228, 2017.939461816148, 2031.1570698160247, 2036.4806974221478, 2038.398672391648, 2039.479932617173, 2024.7772744998038, 2028.4827369020952, 2049.825764809993, 2064.917403303866, 2068.75174979002, 6762.087118239024, 6897.689603967833, 6909.723611055551, 6911.688973996132, 6912.205096413926, 3967.940480892789, 4086.734362266473, 4112.650424713903, 4120.896977575555, 4122.793860307002, 2591.7667409933006, 2744.3276397282793, 2786.986065069682, 2801.887924483018, 2802.607634931111, 813.6501304439089, 845.7885528617845, 865.5239618210022, 878.7715194049529, 882.7660669922476, 9010.59452324647, 9022.413543119676, 9048.502332371718, 9069.319801014884, 9073.09269073094, 3614.963850361463, 3760.5487341519192, 3776.0, 3816.656500914931, 3824.9426258606145, 7683.423743219281, 7686.769396918102, 7687.654055567513, 7688.269614386624, 7702.229777022253, 4145.958540414617, 4152.5432202457705, 4152.688548358885, 4156.272652285898, 4162.0, 3236.0, 3370.1743072947284, 3619.9638003620153, 3799.544054713452, 3804.847806087781, 3212.662670419619, 3248.528963300308, 3364.4616861302075, 3370.898873033813, 3381.3406385754542, 1057.8310966382037, 1079.1420820447763, 1084.6312253833735, 1088.548252475214, 1089.760542086005, 745.9328660420588, 767.1791183433672, 826.8263664630375, 829.618375547255, 829.7261903571807, 4475.776211189427, 4624.6993945394115, 4761.095391875532, 4766.523347665206, 4913.680610760289, 839.6179738219096, 868.4398562926876, 903.4714691905309, 914.1544071733753, 936.5972631768269, 3363.7813542119684, 3464.2725027744455, 3468.358353704573, 3498.3353162898966, 3584.856605735741, 7968.721094761656, 8028.317593004544, 8033.317168040782, 8036.879446808302, 8076.434649574591, 7978.880316795253, 8019.478733882279, 8071.556064416396, 8080.0, 8080.2727754502375, 6091.086337049478, 6342.651154186564, 6348.174737284124, 6485.059666348353, 6491.9350806492275, 4659.93010104842, 4790.904181916272, 4817.0365926814075, 4884.4871288515615, 5192.766325515286, 7363.116426028911, 7578.962105189439, 7648.388128949782, 7655.349295309961, 7656.73201437947, 7617.390608751176, 7729.806754831175, 7806.102298235548, 7808.9609551954145, 7815.062192536705, 5792.0153573893385, 5798.304203495608, 5838.620489668158, 5876.030454974882, 5887.793927212527, 6305.054241863757, 6391.360863913572, 6394.616323020634, 6397.776077837253, 6398.5521013528605, 5903.91144132847, 5982.0, 6021.7591296347655, 6146.907796383147, 6163.96918015407, 5827.155062515827, 5858.47273745348, 5880.764769409347, 5885.529157667291, 5893.2044174035955, 5385.46145385474, 5386.13821788512, 5389.8922021558565, 5394.056040193029, 5426.755795989269, 4036.656884164879, 4051.3517837145914, 4188.554948542748, 4188.832446702219, 4189.853355132555, 3946.684265258715, 4034.9193016138925, 4057.229126465962, 4064.1262128642484, 4190.413342132043], "value": 3707.9230514900537, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.7148794389303, 301.7178937693257, 302.8864175934023, 308.23711314496614, 308.96446908605515, 4718.433787945447, 4847.8545643630705, 4866.85399438017, 4902.779374928128, 4913.238448040551, 4816.446108697501, 4817.542333478323, 4817.662763606549, 4819.662623616348, 4844.152273352167, 3533.7879727216323, 3642.5993140754495, 3648.908777280563, 3731.664150226486, 3738.1402277476195, 3708.830334254451, 3716.5540135183815, 3722.460243264725, 3727.2172843702856, 3735.4957080794015, 3689.7970611616483, 3698.4637227601984, 3705.7961812100325, 3708.369577171887, 3716.3682174030346, 3547.8935631931085, 3565.322588708137, 3602.4596310553366, 3684.410494320909, 3705.184859330943, 3179.60254968129, 3194.8242846643425, 3197.76016798741, 3214.5660335854595, 3224.596925384327, 4670.696404733681, 4672.95327046732, 4673.929891051637, 4684.0, 4695.835645752416, 4189.832406703728, 4191.643710284629, 4209.0, 4226.661867050629, 4232.703710740249, 4887.755612219375, 4964.851054468371, 5001.324821149135, 5003.899922001576, 5006.173981293082, 3444.2422667013216, 3467.2372078142766, 3471.75697701161, 3481.9825900870587, 3485.442329227337, 5210.478952104797, 5226.947730522722, 5243.973780131113, 5300.734963251861, 5316.840494785162, 3397.184675677869, 3552.928941421157, 3553.116848070527, 3577.463380492895, 3582.3521180256853, 3613.6205698401814, 3626.3291291111227, 3634.4548317752547, 3637.108908317426, 3643.289558536113, 4201.096764195712, 4205.747655140702, 4214.641755450821, 4222.9577704223175, 4231.788410579458, 4849.80600775965, 5030.471800460972, 5057.468965758616, 5077.796888124508, 5084.262781896604, 1200.7812070748023, 1204.1931905623253, 1210.2496452199694, 1214.9733475213432, 1215.3254943506438, 787.912680500905, 808.0, 811.3955103447969, 816.5468165168388, 821.9054808697076, 4292.163028209469, 4313.202057619351, 4319.200947824663, 4330.220560299176, 4407.449068866392, 5057.696538207646, 5207.3490813648295, 5211.400688920747, 5216.895946911582, 5218.869528261824, 4471.195184866755, 4482.820687172477, 4490.461144662661, 4490.887727806766, 4491.708038977456, 2626.615507880353, 2643.5638119710034, 2660.93347666306, 2677.6251324814543, 2693.878775455109, 4672.322513235562, 4690.249560070441, 4695.577398034194, 4699.872030712672, 4707.8587642370785, 5170.285600059999, 5177.844664660066, 5183.0, 5202.439268219519, 5223.537409525339, 4533.144119204946, 4693.22561777303, 4721.126591580569, 4731.4558825735485, 4737.360456338419, 4997.250412438163, 5037.848864534069, 5055.747212639427, 5056.064628043824, 5074.289599456153, 4916.213405855046, 4924.596490000333, 4947.431045429822, 4961.776720047535, 4965.950340496694, 4521.6608754344215, 4671.556202160789, 4682.461516925598, 4696.553827386393, 4698.765061746967, 3454.222799870009, 3460.273342597982, 3467.878624248139, 3467.94798078024, 3470.965290347064, 5010.549050585465, 5029.899402012013, 5089.160288552347, 5098.46466121052, 5115.18157094863, 4697.0, 4862.41651001882, 4951.752412379438, 4998.1253280676465, 4999.400311837897, 4832.710037397767, 4836.540528649773, 4847.975760121177, 4863.786976925561, 4872.317875497362, 4792.400949881265, 4814.350062741485, 4898.975505122452, 4909.705417674951, 4916.852494425173, 4675.906481870412, 4923.729194894172, 4947.431045429822, 4956.950430495793, 4982.75086245679, 4785.330053792402, 4799.688020278671, 4812.663113582015, 4837.975810120927, 4839.443464001685], "value": 3717.354431869611, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.3562085137626, 642.2068745099798, 652.1782553981983, 699.6711545573581, 736.8820988641818, 12485.625431237066, 13227.669308267274, 13252.93373533131, 13266.867331326685, 13274.734505309889, 12856.100072994894, 12895.355232238375, 12911.483540658386, 12935.288559129243, 12972.481100755982, 598.2312728144313, 616.8889599872022, 727.7925791149523, 733.7248531800561, 747.2751431111813, 744.4677055905026, 757.7688804914499, 759.2028370211284, 759.6012093650822, 760.817403823084, 807.5477732469835, 809.4374409785202, 809.4495742894829, 809.7773112394079, 810.05628442864, 624.665803794972, 644.5294934697697, 789.5736302396699, 804.1395706593947, 805.472415567804, 583.4107551373102, 588.0855270055063, 588.2470437839572, 588.5850475414823, 589.1016200294542, 12321.0, 12327.260364378077, 12328.32194229317, 12343.19769214998, 12406.317652529107, 13899.305034748324, 14092.506762263272, 14105.858941410557, 14192.574222773334, 14205.502807401692, 10411.947940260327, 10783.13734901206, 10885.945570272177, 10993.890061099446, 11336.43317834105, 3204.3110731192905, 3231.4344989626957, 3274.3287626036604, 3339.2987472630775, 3398.4392575225056, 62062.0, 70346.0, 71498.64250678713, 71519.99999999948, 73039.0, 10910.781784364268, 10969.561217551369, 11000.944995274973, 11017.559297628166, 11244.0, 21107.894460527907, 21322.893385533283, 21350.893245533673, 21580.028898699595, 21581.0, 6573.441257493071, 6622.91228873908, 6680.766173183915, 6719.496037797185, 6960.37356637905, 3271.9182020449202, 3527.2416430467574, 3635.709143268585, 3643.744937854378, 3662.4689420033947, 2712.5931110333213, 2806.242314575053, 2829.122971878724, 2832.9858350708114, 2840.0, 438.97146685465344, 439.5165318150009, 442.09370789880614, 444.74649449813944, 444.9644028477714, 582.2169182449599, 596.6837576084682, 597.3011576455557, 612.1900476811368, 612.451855589254, 7463.328300452985, 7955.960220198977, 8102.716404925799, 8774.780630484162, 8881.555922203865, 504.6820503083034, 556.2129586634948, 589.802416190581, 601.3505414152704, 608.4888693497484, 3776.527934008249, 3944.36890097583, 4000.299947509174, 4057.756534607874, 4105.8973525662095, 65710.0, 66602.66698666476, 67426.32573674299, 69514.0, 69706.37789409998, 57586.0, 58186.709066454394, 58335.0, 58816.41183588195, 58853.41146588565, 53544.46455535472, 53842.0, 54490.45509544854, 54794.72602637041, 55232.17151742727, 7837.7648670539975, 7921.405894557816, 7931.5637639930355, 8141.470804397695, 8156.1744603332845, 35541.28917421689, 35848.59438353352, 35980.64019359773, 36342.818285908405, 37793.81103094467, 38186.98073404505, 45007.324890126016, 45533.31700024436, 51546.484535155665, 52467.47532524626, 10726.839097413702, 10885.074768644754, 10981.890181098086, 11251.943740281245, 12217.144799863925, 12460.190087644274, 12622.179558328678, 12803.359832008176, 12954.417051232906, 13450.932745336602, 11747.236429632047, 11760.176787624785, 12161.391930403268, 12385.19496232742, 12490.500379984698, 11384.715382115513, 11696.47365868555, 11812.881871181178, 12119.0, 12406.76552087167, 11073.224874258725, 11154.386508742096, 11316.15128865322, 11544.307341559534, 11568.0, 9871.654492092743, 9973.700788976343, 10241.692749217535, 10565.366078035342, 10639.0, 9878.802423951625, 10404.167666586807, 10428.0, 10509.474526273809, 10538.57845686164], "value": 6271.470063563404, "unit": "score"}], "name": "dromaeo_css", "value": 6148.364854082464, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 118.0, 118.0, 117.0, 119.0, 118.0, 117.0, 119.0, 116.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 128.0, 130.0, 184.0, 132.0, 131.0, 136.0, 129.0, 156.0, 131.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 160.0, 160.0, 162.0, 161.0, 161.0, 159.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 106.0, 77.0, 78.0, 77.0, 78.0, 91.0, 77.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 107.0, 84.0, 84.0, 82.0, 109.0, 84.0, 84.0, 113.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 148.0, 146.0, 155.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 63.0, 63.0, 64.0, 66.0, 70.0, 64.0, 64.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 88.0, 97.0, 86.0, 95.0, 93.0, 86.0, 92.0, 86.0, 86.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 141.0, 141.0, 146.0, 156.0, 142.0, 147.0, 141.0, 186.0, 145.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 179.0, 181.0, 179.0, 179.0, 178.0, 179.0, 184.0, 178.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 70.0, 70.0, 73.0, 70.0, 72.0, 71.0, 70.0, 70.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1574.4, "alertThreshold": 2.0}]} 15:27:50 INFO - Return code: 0 15:27:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:27:50 INFO - # TBPL SUCCESS # 15:27:50 INFO - Running post-action listener: _resource_record_post_action 15:27:50 INFO - [mozharness: 2016-12-01 23:27:50.468535Z] Finished run-tests step (success) 15:27:50 INFO - Running post-run listener: _resource_record_post_run 15:27:50 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 8323072; Write bytes: 413548544; Read time: 3100; Write time: 357060 15:27:50 INFO - TinderboxPrint: CPU usage
13.1% 15:27:50 INFO - TinderboxPrint: I/O read bytes / time
8,323,072 / 3,100 15:27:50 INFO - TinderboxPrint: I/O write bytes / time
413,548,544 / 357,060 15:27:50 INFO - TinderboxPrint: CPU idle
8,358.1 (86.9%) 15:27:50 INFO - TinderboxPrint: CPU user
1,212.3 (12.6%) 15:27:50 INFO - TinderboxPrint: Swap in / out
0 / 0 15:27:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 90943488; Read time: 180; Write time: 59588 15:27:50 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 5836800; Write bytes: 322605056; Read time: 2204; Write time: 297472 15:27:51 INFO - Running post-run listener: _upload_blobber_files 15:27:51 INFO - Blob upload gear active. 15:27:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:27:51 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 15:27:51 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'] 15:27:51 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 15:27:51 INFO - (blobuploader) - INFO - Open directory for files ... 15:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:27:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:52 INFO - (blobuploader) - INFO - Done attempting. 15:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:55 INFO - (blobuploader) - INFO - Done attempting. 15:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:56 INFO - (blobuploader) - INFO - Done attempting. 15:27:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:27:56 INFO - Return code: 0 15:27:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:27:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:27:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e32959a658187e5b84aba883a5c8a6480e2b2adc6789fbb4371a7feb35d71060da0291bf79e49f35beb0d6349ea2e12a1faaa6c758653d5e6bbf1554ea91a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fad74d79c45a3e2de92126dc95ca4852906d3ad314293e2814938619b4c8c27d73d674b98e4afa0899fdcd4d6d1f5870b4b097ba1a8ff6a00414ab171a364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21874d9391271e3b9d5ab554b092f35b22e1ce8601d55fc060b132705b209130188be78804cb8e6dd185b971a31ae4fee41f8e60b1ca19701224c082b46ae0c5"} 15:27:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:27:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:27:56 INFO - Contents: 15:27:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4e32959a658187e5b84aba883a5c8a6480e2b2adc6789fbb4371a7feb35d71060da0291bf79e49f35beb0d6349ea2e12a1faaa6c758653d5e6bbf1554ea91a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fad74d79c45a3e2de92126dc95ca4852906d3ad314293e2814938619b4c8c27d73d674b98e4afa0899fdcd4d6d1f5870b4b097ba1a8ff6a00414ab171a364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21874d9391271e3b9d5ab554b092f35b22e1ce8601d55fc060b132705b209130188be78804cb8e6dd185b971a31ae4fee41f8e60b1ca19701224c082b46ae0c5"} 15:27:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:27:56 INFO - Copying logs to upload dir... 15:27:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:27:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.227568 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-01 15:27:56.566023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 15:27:56.570175) ========= 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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 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/4e32959a658187e5b84aba883a5c8a6480e2b2adc6789fbb4371a7feb35d71060da0291bf79e49f35beb0d6349ea2e12a1faaa6c758653d5e6bbf1554ea91a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fad74d79c45a3e2de92126dc95ca4852906d3ad314293e2814938619b4c8c27d73d674b98e4afa0899fdcd4d6d1f5870b4b097ba1a8ff6a00414ab171a364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21874d9391271e3b9d5ab554b092f35b22e1ce8601d55fc060b132705b209130188be78804cb8e6dd185b971a31ae4fee41f8e60b1ca19701224c082b46ae0c5"} build_url:https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011703 build_url: 'https://queue.taskcluster.net/v1/task/Id0EFhtpSoeuZ5rullcZcw/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/4e32959a658187e5b84aba883a5c8a6480e2b2adc6789fbb4371a7feb35d71060da0291bf79e49f35beb0d6349ea2e12a1faaa6c758653d5e6bbf1554ea91a50", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/445fad74d79c45a3e2de92126dc95ca4852906d3ad314293e2814938619b4c8c27d73d674b98e4afa0899fdcd4d6d1f5870b4b097ba1a8ff6a00414ab171a364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/21874d9391271e3b9d5ab554b092f35b22e1ce8601d55fc060b132705b209130188be78804cb8e6dd185b971a31ae4fee41f8e60b1ca19701224c082b46ae0c5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 15:27:56.596078) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:27:56.596464) ========= 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=31acecf42b6a79ec49471d4a000001bb-1480633622.189377-632929840 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 15:27:56.679556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 15:27:56.679871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 15:27:56.680157) ========= ========= Total master_lag: 0.40 =========