builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1483464583.13 results: success (0) buildid: 20170103074820 builduid: 21a0f0db02d84a778bd6b50546281e7f revision: 218db8b47b80de47bf6fe624aea9c545a3e01e4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.134083) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.134562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.134869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.150319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.150713) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-03 09:29:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 230M=0s 2017-01-03 09:29:43 (230 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083291 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.247595) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.247914) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020966 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.297721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.298073) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 218db8b47b80de47bf6fe624aea9c545a3e01e4e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 218db8b47b80de47bf6fe624aea9c545a3e01e4e --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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-03 09:29:43,401 truncating revision to first 12 chars 2017-01-03 09:29:43,401 Setting DEBUG logging. 2017-01-03 09:29:43,401 attempt 1/10 2017-01-03 09:29:43,401 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/218db8b47b80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 09:29:43,723 unpacking tar archive at: mozilla-aurora-218db8b47b80/testing/mozharness/ program finished with exit code 0 elapsedTime=0.615236 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.926608) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.926949) ========= script_repo_revision: 218db8b47b80de47bf6fe624aea9c545a3e01e4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.927364) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.927640) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 09:29:43.938227) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-01-03 09:29:43.938608) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:29:44 INFO - MultiFileLogger online at 20170103 09:29:44 in /builds/slave/test 09:29:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:29:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:44 INFO - {'append_to_log': False, 09:29:44 INFO - 'base_work_dir': '/builds/slave/test', 09:29:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:29:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:44 INFO - 'branch': 'Mozilla-Aurora', 09:29:44 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:44 INFO - 'config_files': ('talos/linux_config.py',), 09:29:44 INFO - 'default_actions': ('clobber', 09:29:44 INFO - 'read-buildbot-config', 09:29:44 INFO - 'download-and-extract', 09:29:44 INFO - 'populate-webroot', 09:29:44 INFO - 'create-virtualenv', 09:29:44 INFO - 'install', 09:29:44 INFO - 'run-tests'), 09:29:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:44 INFO - 'download_minidump_stackwalk': True, 09:29:44 INFO - 'download_symbols': 'ondemand', 09:29:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:44 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:44 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:44 INFO - 'installer_path': 'installer.exe', 09:29:44 INFO - 'log_level': 'info', 09:29:44 INFO - 'log_name': 'talos', 09:29:44 INFO - 'log_to_console': True, 09:29:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:29:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:29:44 INFO - 'opt_config_files': (), 09:29:44 INFO - 'pip_index': False, 09:29:44 INFO - 'sps_profile': False, 09:29:44 INFO - 'sps_profile_interval': 0, 09:29:44 INFO - 'suite': 'dromaeojs', 09:29:44 INFO - 'system_bits': '32', 09:29:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:44 INFO - 'title': 'talos-linux64-ix-064', 09:29:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:44 INFO - 'use_talos_json': True, 09:29:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:44 INFO - 'work_dir': 'build'} 09:29:44 INFO - [mozharness: 2017-01-03 17:29:44.138751Z] Running clobber step. 09:29:44 INFO - Running pre-action listener: _resource_record_pre_action 09:29:44 INFO - Running main action method: clobber 09:29:44 INFO - rmtree: /builds/slave/test/build 09:29:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:44 INFO - Running post-action listener: _resource_record_post_action 09:29:44 INFO - [mozharness: 2017-01-03 17:29:44.590291Z] Finished clobber step (success) 09:29:44 INFO - [mozharness: 2017-01-03 17:29:44.590390Z] Running read-buildbot-config step. 09:29:44 INFO - Running pre-action listener: _resource_record_pre_action 09:29:44 INFO - Running main action method: read_buildbot_config 09:29:44 INFO - Using buildbot properties: 09:29:44 INFO - { 09:29:44 INFO - "project": "", 09:29:44 INFO - "product": "firefox", 09:29:44 INFO - "script_repo_revision": "production", 09:29:44 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:29:44 INFO - "repository": "", 09:29:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:29:44 INFO - "buildid": "20170103074820", 09:29:44 INFO - "pgo_build": "True", 09:29:44 INFO - "basedir": "/builds/slave/test", 09:29:44 INFO - "buildnumber": 706, 09:29:44 INFO - "slavename": "talos-linux64-ix-064", 09:29:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:29:44 INFO - "platform": "ubuntu64_hw", 09:29:44 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:29:44 INFO - "branch": "mozilla-aurora", 09:29:44 INFO - "repo_path": "releases/mozilla-aurora", 09:29:44 INFO - "revision": "218db8b47b80de47bf6fe624aea9c545a3e01e4e", 09:29:44 INFO - "stage_platform": "linux64-pgo", 09:29:44 INFO - "builduid": "21a0f0db02d84a778bd6b50546281e7f", 09:29:44 INFO - "slavebuilddir": "test" 09:29:44 INFO - } 09:29:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 09:29:44 INFO - Running post-action listener: _resource_record_post_action 09:29:44 INFO - [mozharness: 2017-01-03 17:29:44.593136Z] Finished read-buildbot-config step (success) 09:29:44 INFO - [mozharness: 2017-01-03 17:29:44.593224Z] Running download-and-extract step. 09:29:44 INFO - Running pre-action listener: _resource_record_pre_action 09:29:44 INFO - Running main action method: download_and_extract 09:29:44 INFO - mkdir: /builds/slave/test/build/tests 09:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:44 INFO - trying https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:29:44 INFO - Downloading https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:29:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:29:45 INFO - Downloaded 1361 bytes. 09:29:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 09:29:45 INFO - Using the following test package requirements: 09:29:45 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:29:45 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:29:45 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:29:45 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'jsshell-linux-x86_64.zip'], 09:29:45 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:29:45 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 09:29:45 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:29:45 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:29:45 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:29:45 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 09:29:45 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:29:45 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:29:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:29:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:29:45 INFO - Fetch https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:29:45 INFO - Http code: 200 09:29:45 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 09:29:45 INFO - x-amz-cf-id: gGnEPMkvqiRew9Iswd8Yu1lx2r97-Lh4udfatg7Cerva-V4JSlySlA== 09:29:45 INFO - x-amz-version-id: bSoipvmtmSrYoBm8pR279XDuxfftF_Wa 09:29:45 INFO - Expected file size: 21942557 09:29:45 INFO - Obtained file size: 21942557 09:29:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:29:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 09:29:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:29:50 INFO - Fetch https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:29:50 INFO - Http code: 200 09:29:50 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 09:29:50 INFO - x-amz-cf-id: j7bl5M5vdeFluOosS842nKAqr-wdoh-fIApHsg9QG1Lzk5mBrMUn_w== 09:29:50 INFO - x-amz-version-id: bSoipvmtmSrYoBm8pR279XDuxfftF_Wa 09:29:50 INFO - Expected file size: 21942557 09:29:50 INFO - Obtained file size: 21942557 09:29:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 09:29:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:29:55 INFO - Fetch https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:29:55 INFO - Http code: 200 09:29:55 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 09:29:55 INFO - x-amz-cf-id: MJMZQsjbGX1GFm59oLtiUnejwhYJAgJBLpYjrc1z35TufLOH2YE72Q== 09:29:55 INFO - x-amz-version-id: aFIoK4MKxCAgHlEX1eHiXsr7al7nD5iM 09:29:55 INFO - Expected file size: 14409038 09:29:55 INFO - Obtained file size: 14409038 09:29:56 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')]} 09:29:56 INFO - trying https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:29:56 INFO - Downloading https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:29:57 INFO - Downloaded 64008469 bytes. 09:29:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:29:57 INFO - mkdir: /builds/slave/test/properties 09:29:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:57 INFO - Contents: 09:29:57 INFO - build_url:https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 09:29:58 INFO - Running post-action listener: _resource_record_post_action 09:29:58 INFO - Running post-action listener: set_extra_try_arguments 09:29:58 INFO - [mozharness: 2017-01-03 17:29:58.110063Z] Finished download-and-extract step (success) 09:29:58 INFO - [mozharness: 2017-01-03 17:29:58.110291Z] Running populate-webroot step. 09:29:58 INFO - Running pre-action listener: _resource_record_pre_action 09:29:58 INFO - Running main action method: populate_webroot 09:29:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:58 INFO - '--tppagecycles', 09:29:58 INFO - '7'], 09:29:58 INFO - 'tests': ['tsvgm']}}, 09:29:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['damp', 'tps']}, 09:29:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['damp', 'tps']}, 09:29:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['dromaeo_dom']}, 09:29:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['basic_compositor_video']}, 09:29:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['a11yr', 09:29:58 INFO - 'ts_paint', 09:29:58 INFO - 'tpaint', 09:29:58 INFO - 'sessionrestore', 09:29:58 INFO - 'sessionrestore_no_auto_restore', 09:29:58 INFO - 'tabpaint']}, 09:29:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['tsvgx', 09:29:58 INFO - 'tsvgr_opacity', 09:29:58 INFO - 'tart', 09:29:58 INFO - 'tscrollx', 09:29:58 INFO - 'cart']}, 09:29:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:58 INFO - 'tsvgr_opacity', 09:29:58 INFO - 'tart', 09:29:58 INFO - 'tscrollx', 09:29:58 INFO - 'cart']}, 09:29:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--disable-e10s'], 09:29:58 INFO - 'tests': ['tp5o']}, 09:29:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'tests': ['tp5o']}, 09:29:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--disable-e10s', 09:29:58 INFO - '--xperf_path', 09:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:58 INFO - 'tests': ['tp5n']}, 09:29:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:58 INFO - 'talos_options': ['--xperf_path', 09:29:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:58 INFO - 'tests': ['tp5n']}}, 09:29:58 INFO - 'talos.zip': {'path': '', 09:29:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:58 INFO - Running post-action listener: _resource_record_post_action 09:29:58 INFO - [mozharness: 2017-01-03 17:29:58.126776Z] Finished populate-webroot step (success) 09:29:58 INFO - [mozharness: 2017-01-03 17:29:58.126882Z] Running create-virtualenv step. 09:29:58 INFO - Running pre-action listener: _resource_record_pre_action 09:29:58 INFO - Running main action method: create_virtualenv 09:29:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:58 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 09:29:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:58 INFO - Using real prefix '/usr' 09:29:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:00 INFO - Installing pip.................done. 09:30:00 INFO - Return code: 0 09:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:30:00 INFO - Reading from file tmpfile_stdout 09:30:00 INFO - Output received: 09:30:00 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) 09:30:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:00 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')]} 09:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 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')]} 09:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:00 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:00 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 09:30:00 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 09:30:00 INFO - Using env: {'DISPLAY': ':0', 09:30:00 INFO - 'HOME': '/home/cltbld', 09:30:00 INFO - 'LANG': 'en_US.UTF-8', 09:30:00 INFO - 'LANGUAGE': 'en_US:en', 09:30:00 INFO - 'LOGNAME': 'cltbld', 09:30:00 INFO - 'MAIL': '/var/mail/cltbld', 09:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:00 INFO - 'MOZ_NO_REMOTE': '1', 09:30:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:00 INFO - 'NO_EM_RESTART': '1', 09:30:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:00 INFO - 'PWD': '/builds/slave/test', 09:30:00 INFO - 'SHELL': '/bin/bash', 09:30:00 INFO - 'SHLVL': '1', 09:30:00 INFO - 'TERM': 'linux', 09:30:00 INFO - 'TMOUT': '86400', 09:30:00 INFO - 'USER': 'cltbld', 09:30:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:00 INFO - '_': '/tools/buildbot/bin/python'} 09:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:01 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 09:30:01 INFO - Cleaning up... 09:30:01 INFO - Return code: 0 09:30:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:01 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:01 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 09:30:01 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 09:30:01 INFO - Using env: {'DISPLAY': ':0', 09:30:01 INFO - 'HOME': '/home/cltbld', 09:30:01 INFO - 'LANG': 'en_US.UTF-8', 09:30:01 INFO - 'LANGUAGE': 'en_US:en', 09:30:01 INFO - 'LOGNAME': 'cltbld', 09:30:01 INFO - 'MAIL': '/var/mail/cltbld', 09:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:01 INFO - 'MOZ_NO_REMOTE': '1', 09:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:01 INFO - 'NO_EM_RESTART': '1', 09:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:01 INFO - 'PWD': '/builds/slave/test', 09:30:01 INFO - 'SHELL': '/bin/bash', 09:30:01 INFO - 'SHLVL': '1', 09:30:01 INFO - 'TERM': 'linux', 09:30:01 INFO - 'TMOUT': '86400', 09:30:01 INFO - 'USER': 'cltbld', 09:30:01 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:01 INFO - '_': '/tools/buildbot/bin/python'} 09:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:01 INFO - Downloading/unpacking psutil>=3.1.1 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:03 INFO - Installing collected packages: psutil 09:30:03 INFO - Running setup.py install for psutil 09:30:03 INFO - building 'psutil._psutil_linux' extension 09:30:03 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 09:30:03 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 09:30:03 INFO - building 'psutil._psutil_posix' extension 09:30:03 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 09:30:03 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 09:30:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:04 INFO - Successfully installed psutil 09:30:04 INFO - Cleaning up... 09:30:04 INFO - Return code: 0 09:30:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30:04 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')]} 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:04 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')]} 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:04 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:04 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 09:30:04 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 09:30:04 INFO - Using env: {'DISPLAY': ':0', 09:30:04 INFO - 'HOME': '/home/cltbld', 09:30:04 INFO - 'LANG': 'en_US.UTF-8', 09:30:04 INFO - 'LANGUAGE': 'en_US:en', 09:30:04 INFO - 'LOGNAME': 'cltbld', 09:30:04 INFO - 'MAIL': '/var/mail/cltbld', 09:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:04 INFO - 'MOZ_NO_REMOTE': '1', 09:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:04 INFO - 'NO_EM_RESTART': '1', 09:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:04 INFO - 'PWD': '/builds/slave/test', 09:30:04 INFO - 'SHELL': '/bin/bash', 09:30:04 INFO - 'SHLVL': '1', 09:30:04 INFO - 'TERM': 'linux', 09:30:04 INFO - 'TMOUT': '86400', 09:30:04 INFO - 'USER': 'cltbld', 09:30:04 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:04 INFO - '_': '/tools/buildbot/bin/python'} 09:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:04 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 09:30:04 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 09:30:04 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 09:30:04 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 09:30:04 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 09:30:04 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 09:30:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:06 INFO - Installing collected packages: mozsystemmonitor 09:30:06 INFO - Running setup.py install for mozsystemmonitor 09:30:06 INFO - Successfully installed mozsystemmonitor 09:30:06 INFO - Cleaning up... 09:30:06 INFO - Return code: 0 09:30:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:30: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')]} 09:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30: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')]} 09:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:06 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:06 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 09:30:06 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 09:30:06 INFO - Using env: {'DISPLAY': ':0', 09:30:06 INFO - 'HOME': '/home/cltbld', 09:30:06 INFO - 'LANG': 'en_US.UTF-8', 09:30:06 INFO - 'LANGUAGE': 'en_US:en', 09:30:06 INFO - 'LOGNAME': 'cltbld', 09:30:06 INFO - 'MAIL': '/var/mail/cltbld', 09:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:06 INFO - 'MOZ_NO_REMOTE': '1', 09:30:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:06 INFO - 'NO_EM_RESTART': '1', 09:30:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:06 INFO - 'PWD': '/builds/slave/test', 09:30:06 INFO - 'SHELL': '/bin/bash', 09:30:06 INFO - 'SHLVL': '1', 09:30:06 INFO - 'TERM': 'linux', 09:30:06 INFO - 'TMOUT': '86400', 09:30:06 INFO - 'USER': 'cltbld', 09:30:06 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:06 INFO - '_': '/tools/buildbot/bin/python'} 09:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:06 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 09:30:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:08 INFO - warning: no files found matching '*.txt' 09:30:08 INFO - no previously-included directories found matching 'build' 09:30:08 INFO - no previously-included directories found matching 'dist' 09:30:08 INFO - no previously-included directories found matching '.git*' 09:30:09 INFO - Installing collected packages: jsonschema, functools32 09:30:09 INFO - Running setup.py install for functools32 09:30:09 INFO - warning: no files found matching '*.txt' 09:30:09 INFO - no previously-included directories found matching 'build' 09:30:09 INFO - no previously-included directories found matching 'dist' 09:30:09 INFO - no previously-included directories found matching '.git*' 09:30:09 INFO - Successfully installed jsonschema functools32 09:30:09 INFO - Cleaning up... 09:30:09 INFO - Return code: 0 09:30:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:30:09 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')]} 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 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')]} 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:09 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 09:30:09 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 09:30:09 INFO - Using env: {'DISPLAY': ':0', 09:30:09 INFO - 'HOME': '/home/cltbld', 09:30:09 INFO - 'LANG': 'en_US.UTF-8', 09:30:09 INFO - 'LANGUAGE': 'en_US:en', 09:30:09 INFO - 'LOGNAME': 'cltbld', 09:30:09 INFO - 'MAIL': '/var/mail/cltbld', 09:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:09 INFO - 'MOZ_NO_REMOTE': '1', 09:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:09 INFO - 'NO_EM_RESTART': '1', 09:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:09 INFO - 'PWD': '/builds/slave/test', 09:30:09 INFO - 'SHELL': '/bin/bash', 09:30:09 INFO - 'SHLVL': '1', 09:30:09 INFO - 'TERM': 'linux', 09:30:09 INFO - 'TMOUT': '86400', 09:30:09 INFO - 'USER': 'cltbld', 09:30:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:09 INFO - '_': '/tools/buildbot/bin/python'} 09:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:30:09 INFO - Cleaning up... 09:30:09 INFO - Return code: 0 09:30:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:09 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')]} 09:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 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')]} 09:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:09 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:09 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 09:30:09 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 09:30:09 INFO - Using env: {'DISPLAY': ':0', 09:30:09 INFO - 'HOME': '/home/cltbld', 09:30:09 INFO - 'LANG': 'en_US.UTF-8', 09:30:09 INFO - 'LANGUAGE': 'en_US:en', 09:30:09 INFO - 'LOGNAME': 'cltbld', 09:30:09 INFO - 'MAIL': '/var/mail/cltbld', 09:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:09 INFO - 'MOZ_NO_REMOTE': '1', 09:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:09 INFO - 'NO_EM_RESTART': '1', 09:30:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:09 INFO - 'PWD': '/builds/slave/test', 09:30:09 INFO - 'SHELL': '/bin/bash', 09:30:09 INFO - 'SHLVL': '1', 09:30:09 INFO - 'TERM': 'linux', 09:30:09 INFO - 'TMOUT': '86400', 09:30:09 INFO - 'USER': 'cltbld', 09:30:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:09 INFO - '_': '/tools/buildbot/bin/python'} 09:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:09 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 09:30:09 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 09:30:09 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 09:30:09 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 09:30:09 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 09:30:09 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 09:30:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:12 INFO - Downloading docopt-0.6.1.tar.gz 09:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:12 INFO - Running setup.py install for blobuploader 09:30:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:30:12 INFO - Running setup.py install for requests 09:30:13 INFO - Running setup.py install for docopt 09:30:13 INFO - Successfully installed blobuploader requests docopt 09:30:13 INFO - Cleaning up... 09:30:13 INFO - Return code: 0 09:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:13 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:13 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 09:30:13 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 09:30:13 INFO - Using env: {'DISPLAY': ':0', 09:30:13 INFO - 'HOME': '/home/cltbld', 09:30:13 INFO - 'LANG': 'en_US.UTF-8', 09:30:13 INFO - 'LANGUAGE': 'en_US:en', 09:30:13 INFO - 'LOGNAME': 'cltbld', 09:30:13 INFO - 'MAIL': '/var/mail/cltbld', 09:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:13 INFO - 'MOZ_NO_REMOTE': '1', 09:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:13 INFO - 'NO_EM_RESTART': '1', 09:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:13 INFO - 'PWD': '/builds/slave/test', 09:30:13 INFO - 'SHELL': '/bin/bash', 09:30:13 INFO - 'SHLVL': '1', 09:30:13 INFO - 'TERM': 'linux', 09:30:13 INFO - 'TMOUT': '86400', 09:30:13 INFO - 'USER': 'cltbld', 09:30:13 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:13 INFO - '_': '/tools/buildbot/bin/python'} 09:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:13 INFO - Running setup.py (path:/tmp/pip-IxQjH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:13 INFO - Running setup.py (path:/tmp/pip-cqYEmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:13 INFO - Running setup.py (path:/tmp/pip-bRu3U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:13 INFO - Running setup.py (path:/tmp/pip-G_nyrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:13 INFO - Running setup.py (path:/tmp/pip-0zEFMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:13 INFO - Running setup.py (path:/tmp/pip-lyqT7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:13 INFO - Running setup.py (path:/tmp/pip-eudRPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:13 INFO - Running setup.py (path:/tmp/pip-B4f872-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:13 INFO - Running setup.py (path:/tmp/pip-Uf0VPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:14 INFO - Running setup.py (path:/tmp/pip-j8CgIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:14 INFO - Running setup.py (path:/tmp/pip-pYRMpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:14 INFO - Running setup.py (path:/tmp/pip-jgZUQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:14 INFO - Running setup.py (path:/tmp/pip-_OGS3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:14 INFO - Running setup.py (path:/tmp/pip-NEeRwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:14 INFO - Running setup.py (path:/tmp/pip-h00H7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:14 INFO - Running setup.py (path:/tmp/pip-XsKo_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:14 INFO - Running setup.py (path:/tmp/pip-MqoQ74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:14 INFO - Running setup.py install for manifestparser 09:30:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:14 INFO - Running setup.py install for mozcrash 09:30:14 INFO - Running setup.py install for mozdebug 09:30:14 INFO - Running setup.py install for mozdevice 09:30:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozfile 09:30:15 INFO - Running setup.py install for mozhttpd 09:30:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozinfo 09:30:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozInstall 09:30:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozleak 09:30:15 INFO - Running setup.py install for mozlog 09:30:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for moznetwork 09:30:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozprocess 09:30:15 INFO - Running setup.py install for mozprofile 09:30:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozrunner 09:30:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozscreenshot 09:30:16 INFO - Running setup.py install for moztest 09:30:16 INFO - Running setup.py install for mozversion 09:30:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:16 INFO - Cleaning up... 09:30:16 INFO - Return code: 0 09:30:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:16 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:16 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 09:30:16 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 09:30:16 INFO - Using env: {'DISPLAY': ':0', 09:30:16 INFO - 'HOME': '/home/cltbld', 09:30:16 INFO - 'LANG': 'en_US.UTF-8', 09:30:16 INFO - 'LANGUAGE': 'en_US:en', 09:30:16 INFO - 'LOGNAME': 'cltbld', 09:30:16 INFO - 'MAIL': '/var/mail/cltbld', 09:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:16 INFO - 'MOZ_NO_REMOTE': '1', 09:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:16 INFO - 'NO_EM_RESTART': '1', 09:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:16 INFO - 'PWD': '/builds/slave/test', 09:30:16 INFO - 'SHELL': '/bin/bash', 09:30:16 INFO - 'SHLVL': '1', 09:30:16 INFO - 'TERM': 'linux', 09:30:16 INFO - 'TMOUT': '86400', 09:30:16 INFO - 'USER': 'cltbld', 09:30:16 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:16 INFO - '_': '/tools/buildbot/bin/python'} 09:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:16 INFO - Running setup.py (path:/tmp/pip-Bnzrsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:16 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)) 09:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:16 INFO - Running setup.py (path:/tmp/pip-rVVQbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:16 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)) 09:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:16 INFO - Running setup.py (path:/tmp/pip-rvizbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:16 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)) 09:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:16 INFO - Running setup.py (path:/tmp/pip-xNOaq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:16 INFO - Running setup.py (path:/tmp/pip-4YUAju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:17 INFO - Running setup.py (path:/tmp/pip-t0KzrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:17 INFO - Running setup.py (path:/tmp/pip-3g2wjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:17 INFO - Running setup.py (path:/tmp/pip-Mc4Y3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:17 INFO - Running setup.py (path:/tmp/pip-5flwqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:17 INFO - Running setup.py (path:/tmp/pip-pTrbUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:17 INFO - Running setup.py (path:/tmp/pip-KeejvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:17 INFO - Running setup.py (path:/tmp/pip-eAegs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:17 INFO - Running setup.py (path:/tmp/pip-96H46J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:17 INFO - Running setup.py (path:/tmp/pip-7Bjo2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:17 INFO - Running setup.py (path:/tmp/pip-BUwaFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:17 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:17 INFO - Running setup.py (path:/tmp/pip-BcsRpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:30:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:17 INFO - Running setup.py (path:/tmp/pip-8GYqZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:17 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)) 09:30:17 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)) 09:30:17 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)) 09:30:17 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)) 09:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:20 INFO - Downloading blessings-1.6.tar.gz 09:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:20 INFO - Installing collected packages: blessings 09:30:20 INFO - Running setup.py install for blessings 09:30:20 INFO - Successfully installed blessings 09:30:20 INFO - Cleaning up... 09:30:20 INFO - Return code: 0 09:30:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:20 INFO - Reading from file tmpfile_stdout 09:30:20 INFO - Current package versions: 09:30:20 INFO - argparse == 1.2.1 09:30:20 INFO - blessings == 1.6 09:30:20 INFO - blobuploader == 1.2.4 09:30:20 INFO - docopt == 0.6.1 09:30:20 INFO - functools32 == 3.2.3-2 09:30:20 INFO - jsonschema == 2.5.1 09:30:20 INFO - manifestparser == 1.1 09:30:20 INFO - mozInstall == 1.12 09:30:20 INFO - mozcrash == 1.0 09:30:20 INFO - mozdebug == 0.1 09:30:20 INFO - mozdevice == 0.48 09:30:20 INFO - mozfile == 1.2 09:30:20 INFO - mozhttpd == 0.7 09:30:20 INFO - mozinfo == 0.9 09:30:20 INFO - mozleak == 0.1 09:30:20 INFO - mozlog == 3.4 09:30:20 INFO - moznetwork == 0.27 09:30:20 INFO - mozprocess == 0.23 09:30:20 INFO - mozprofile == 0.28 09:30:20 INFO - mozrunner == 6.13 09:30:20 INFO - mozscreenshot == 0.1 09:30:20 INFO - mozsystemmonitor == 0.3 09:30:20 INFO - moztest == 0.8 09:30:20 INFO - mozversion == 1.4 09:30:20 INFO - psutil == 3.1.1 09:30:20 INFO - requests == 1.2.3 09:30:20 INFO - wsgiref == 0.1.2 09:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:20 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')]} 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 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')]} 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:20 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 09:30:20 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 09:30:20 INFO - Using env: {'DISPLAY': ':0', 09:30:20 INFO - 'HOME': '/home/cltbld', 09:30:20 INFO - 'LANG': 'en_US.UTF-8', 09:30:20 INFO - 'LANGUAGE': 'en_US:en', 09:30:20 INFO - 'LOGNAME': 'cltbld', 09:30:20 INFO - 'MAIL': '/var/mail/cltbld', 09:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:20 INFO - 'MOZ_NO_REMOTE': '1', 09:30:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:20 INFO - 'NO_EM_RESTART': '1', 09:30:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:20 INFO - 'PWD': '/builds/slave/test', 09:30:20 INFO - 'SHELL': '/bin/bash', 09:30:20 INFO - 'SHLVL': '1', 09:30:20 INFO - 'TERM': 'linux', 09:30:20 INFO - 'TMOUT': '86400', 09:30:20 INFO - 'USER': 'cltbld', 09:30:20 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:20 INFO - '_': '/tools/buildbot/bin/python'} 09:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 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)) 09:30:20 INFO - Cleaning up... 09:30:20 INFO - Return code: 0 09:30:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:20 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')]} 09:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 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')]} 09:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:20 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 0x7ff63928df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274cca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', '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 09:30:20 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 09:30:20 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 09:30:20 INFO - Using env: {'DISPLAY': ':0', 09:30:20 INFO - 'HOME': '/home/cltbld', 09:30:20 INFO - 'LANG': 'en_US.UTF-8', 09:30:20 INFO - 'LANGUAGE': 'en_US:en', 09:30:20 INFO - 'LOGNAME': 'cltbld', 09:30:20 INFO - 'MAIL': '/var/mail/cltbld', 09:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:20 INFO - 'MOZ_NO_REMOTE': '1', 09:30:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:20 INFO - 'NO_EM_RESTART': '1', 09:30:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:20 INFO - 'PWD': '/builds/slave/test', 09:30:20 INFO - 'SHELL': '/bin/bash', 09:30:20 INFO - 'SHLVL': '1', 09:30:20 INFO - 'TERM': 'linux', 09:30:20 INFO - 'TMOUT': '86400', 09:30:20 INFO - 'USER': 'cltbld', 09:30:20 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:20 INFO - '_': '/tools/buildbot/bin/python'} 09:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:30:21 INFO - Cleaning up... 09:30:21 INFO - Return code: 0 09:30:21 INFO - Running post-action listener: _resource_record_post_action 09:30:21 INFO - Running post-action listener: _start_resource_monitoring 09:30:21 INFO - Starting resource monitoring. 09:30:21 INFO - [mozharness: 2017-01-03 17:30:21.074609Z] Finished create-virtualenv step (success) 09:30:21 INFO - [mozharness: 2017-01-03 17:30:21.075108Z] Running install step. 09:30:21 INFO - Running pre-action listener: _resource_record_pre_action 09:30:21 INFO - Running main action method: install 09:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:21 INFO - Reading from file tmpfile_stdout 09:30:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:21 INFO - Reading from file tmpfile_stdout 09:30:21 INFO - Output received: 09:30:21 INFO - Usage: mozinstall [options] installer 09:30:21 INFO - Options: 09:30:21 INFO - -h, --help show this help message and exit 09:30:21 INFO - -d DEST, --destination=DEST 09:30:21 INFO - Directory to install application into. [default: 09:30:21 INFO - "/builds/slave/test"] 09:30:21 INFO - --app=APP Application being installed. [default: firefox] 09:30:21 INFO - mkdir: /builds/slave/test/build/application 09:30:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:30:37 INFO - Reading from file tmpfile_stdout 09:30:37 INFO - Output received: 09:30:37 INFO - /builds/slave/test/build/application/firefox/firefox 09:30:37 INFO - Running post-action listener: _resource_record_post_action 09:30:37 INFO - [mozharness: 2017-01-03 17:30:37.289697Z] Finished install step (success) 09:30:37 INFO - [mozharness: 2017-01-03 17:30:37.289942Z] Running run-tests step. 09:30:37 INFO - Running pre-action listener: _resource_record_pre_action 09:30:37 INFO - Running main action method: run_tests 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:37 INFO - Python 2.7.3 09:30:37 INFO - Return code: 0 09:30:37 INFO - grabbing minidump binary from tooltool 09:30:37 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')]} 09:30:37 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 0x2749f60>, '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 0x274ad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x274b1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:37 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 09:30:37 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 09:30:37 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 09:30:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:30:37 INFO - Return code: 0 09:30:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:30:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:30:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 09:30:37 INFO - Using env: {'DISPLAY': ':0', 09:30:37 INFO - 'HOME': '/home/cltbld', 09:30:37 INFO - 'LANG': 'en_US.UTF-8', 09:30:37 INFO - 'LANGUAGE': 'en_US:en', 09:30:37 INFO - 'LOGNAME': 'cltbld', 09:30:37 INFO - 'MAIL': '/var/mail/cltbld', 09:30:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:30:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:37 INFO - 'MOZ_NO_REMOTE': '1', 09:30:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:37 INFO - 'NO_EM_RESTART': '1', 09:30:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:37 INFO - 'PWD': '/builds/slave/test', 09:30:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:30:37 INFO - 'SHELL': '/bin/bash', 09:30:37 INFO - 'SHLVL': '1', 09:30:37 INFO - 'TERM': 'linux', 09:30:37 INFO - 'TMOUT': '86400', 09:30:37 INFO - 'USER': 'cltbld', 09:30:37 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296', 09:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:37 INFO - '_': '/tools/buildbot/bin/python'} 09:30:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 09:30:37 INFO - mozversion application_buildid: 20170103074820 09:30:37 INFO - mozversion application_changeset: 218db8b47b80de47bf6fe624aea9c545a3e01e4e 09:30:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:30:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:30:37 INFO - mozversion application_name: Firefox 09:30:37 INFO - mozversion application_remotingname: firefox-dev 09:30:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:37 INFO - mozversion application_vendor: Mozilla 09:30:37 INFO - mozversion application_version: 52.0a2 09:30:37 INFO - mozversion platform_buildid: 20170103074820 09:30:37 INFO - mozversion platform_changeset: 218db8b47b80de47bf6fe624aea9c545a3e01e4e 09:30:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:30:37 INFO - mozversion platform_version: 52.0a2 09:30:37 INFO - using testdate: 1483464637 09:30:37 INFO - actual date: 1483464637 09:30:37 INFO - starting webserver on 'localhost:58563' 09:30:37 INFO - SUITE-START | Running 2 tests 09:30:37 INFO - TEST-START | dromaeo_css 09:30:37 INFO - operating with platform_type : linux_ 09:30:37 INFO - Initialising browser for dromaeo_css test... 09:30:37 INFO - TEST-INFO | started process 9806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJkZjyt/profile http://localhost:58563/getInfo.html) 09:30:41 INFO - PROCESS | 9806 | __metrics Screen width/height:1600/1200 09:30:41 INFO - PROCESS | 9806 | colorDepth:24 09:30:41 INFO - PROCESS | 9806 | Browser inner width/height: 1024/697 09:30:41 INFO - PROCESS | 9806 | __metrics 09:30:42 INFO - PROCESS | 9806 | 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" 09:30:42 INFO - PROCESS | 9806 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:43 INFO - PROCESS | 9806 | console.error: PushService: 09:30:43 INFO - PROCESS | 9806 | stateChangeProcessEnqueue: Error transitioning state 09:30:43 INFO - PROCESS | 9806 | UnknownError 09:30:43 INFO - TEST-INFO | 9806: exit 0 09:30:43 INFO - Browser initialized. 09:30:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:30:43 INFO - TEST-INFO | started process 9897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJkZjyt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:55 INFO - PROCESS | 9897 | RSS: Main: 144371712 09:30:55 INFO - PROCESS | 9897 | 09:31:46 INFO - PROCESS | 9897 | 1483464706478 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] 09:33:56 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58563/tests/dromaeo/cssquery-ext.html) 09:33:58 INFO - PROCESS | 9897 | RSS: Main: 236670976 09:33:58 INFO - PROCESS | 9897 | 09:37:00 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-ext.html (next: http://localhost:58563/tests/dromaeo/cssquery-jquery.html) 09:37:01 INFO - PROCESS | 9897 | RSS: Main: 224669696 09:37:01 INFO - PROCESS | 9897 | 09:40:03 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58563/tests/dromaeo/cssquery-mootools.html) 09:40:03 INFO - PROCESS | 9897 | RSS: Main: 219185152 09:40:03 INFO - PROCESS | 9897 | 09:43:05 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58563/tests/dromaeo/cssquery-prototype.html) 09:43:06 INFO - PROCESS | 9897 | RSS: Main: 227176448 09:43:06 INFO - PROCESS | 9897 | 09:46:09 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58563/tests/dromaeo/cssquery-yui.html) 09:46:09 INFO - PROCESS | 9897 | RSS: Main: 175796224 09:46:09 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | Cycle 1(1): loaded http://localhost:58563/tests/dromaeo/cssquery-yui.html (next: http://localhost:58563/tests/dromaeo/cssquery-dojo.html) 09:49:12 INFO - PROCESS | 9897 | RSS: Main: 265924608 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | __start_tp_report 09:49:12 INFO - PROCESS | 9897 | _x_x_mozilla_page_load 09:49:12 INFO - PROCESS | 9897 | _x_x_mozilla_page_load_details 09:49:12 INFO - PROCESS | 9897 | |i|pagename|runs| 09:49:12 INFO - PROCESS | 9897 | |0;dojo.html;3174.1588479053057;3209.197700574857;3215.389076075544;3239.465488194448;3252.4470839957203;5346.411894691586;5377.408485066644;5394.217838413428;5409.107497262957;5416.837494875155;5490.890182196354;5494;5494.203340515623;5516.172574113885;5520.696361700105;4969.875753106174;4987.102321582114;5012.172991456406;5043.344365232515;5052.545270925616;4833.057553777015;4856.150173719602;4874.512548745132;5019.974900125494;5081.364829396325;4870.977094810094;4881.780319885614;4895.241237608158;4964.180910149821;4984.302197692308;4984.576311013569;4997.125503036954;5001.949980500185;5006.374203224597;5029.446760856303;4809.470958194595;4908.4846091160625;4934;4950.381202349707;4974.079795237856;5237.8428647140645;5274.683518988873;5284.5772338212855;5353.250544923675;5360.383555891044;5637.943620563783;5644.576656750719;5645.407232240596;5716.256886604715;5730.484256416901;5743.597948143632;5763.049096899049;5798.507126894219;5800.100984347433;5803.245578074865;5308.628396012281;5345.54562862161;5348.304720386363;5378.408375078739;5443.074677304868;5909.143174239711;5918.408159184133;5918.881622367615;5921.940780592225;5925.34821169671;5688.829335119953;5694.4590263924865;5695.117256825199;5705.914411283834;5706.400827913092;5605.990921634064;5654.321481422256;5694.032014557535;5718.142278658234;5724.998125328051;5781.739821708034;5788.797392091256;5789.681567513827;5790.478856902899;5823.56323275756;5773.88452230953;5780.393058728857;5794.623349482355;5797.594168408171;5798.10129429939;4385;4418.513963464016;4451.844185453494;4505.549445055468;4542.659300552472;4092;4103.466549348565;4115.279826030493;4123.237201117803;4144.91710165795;5570.275864137636;5617.41017193197;5620.100783874642;5625.128105143709;5627.971860140673;5592.5805564582815;5643.661380317194;5650.604457688005;5869.06095024794;5873;5507.173923911445;5555.2222688823595;5558.082916318843;5601.60788744784;5632.774689012475;5076.517730815566;5089.083964886282;5090.592752579785;5091.338126043591;5118.974405128025;5931.703414829155;6097.207363042865;6143.631382117087;6169.321374648785;6173.197484327009;5678.829635110953;5690.9430905690415;5715.371309155907;5733.426657334233;5821.155932389865;6042.335343112342;6048.032314829607;6098.847528811815;6101.816945491642;6124.050772130327;5705.686187259741;5710.714464276686;5711.400302968129;5713.800016999385;5715.057015592547;5571.8885622288135;5595.328560572757;5698.971505142614;5753.4246575343805;5760.308762948473;5629.930313240471;5635.718214089197;5644.012297847778;5703.344115426779;5724.656520608777;5618.6628802272;5622.959752445647;5626.831195064155;5627.915581266366;5632.352279487911;5394.892102158014;5448.618596698193;5525.143602741581;5551.361593416808;5629.211910332475;5706.2011318414625;5732.57005724564;5758.827235182951;5759.94240057594;5788.652680839163;5353.357597088218;5606.775728970959;5616.129499927518;5617;5620.437956204347 09:49:12 INFO - PROCESS | 9897 | |1;ext.html;583.4981915552625;614.1739360560047;638.996805015975;646.7994921574315;665.5640555436185;34059;44960.775196124014;45799.999999999956;45917.54082459174;46026.539734602644;46105.76947115268;46330.53669463305;46475;46503.534964650346;46560.76719616406;8413.200745929127;8777.253933415668;8904.8664270036;8914.598843052077;8917.42036767608;8573.95713021434;10529.157667386591;10779.407132607703;10796.028357447829;11080.556777728882;10812.189085818594;10869.293495922779;10878;10881.183911206652;10882.510287037063;10661.893381066206;10711.982361675628;10721.785564288748;10729.302595331279;10742.355458672426;10606.363618182857;10683.412412317319;10688.465576721133;10689.358638481715;10690.946545267223;42724.78637606823;43001;43010.56989430128;43017.784911075556;43092.35361469581;3629.5825980012373;3666.8899933002053;3695.7597756146033;3869;4096.045197740111;32166;32422.189445264055;32599.83700081508;32722.345553089042;32852.342953141044;4345.1961387143165;4382.583654552813;4383.561643835623;4398.3842262083335;4468.173387923244;351254;353631;353771.9245615099;354444;354516.99999999744;37407.81296093502;58912.70543647254;59366.70316648475;59383.40616593865;59384.40615593875;38613.80693096573;40736.18527629431;40837;40882;40910;16780.077095759607;16951.237194326284;16956.576085597713;16966;16979.405720799714;12883.35583220854;13120.737585248242;13778.931105344409;14040;14179.574612761633;9148.313876459291;9297.628094876265;9343.859842102374;9393.154616084585;9404.905950940403;3554.5023696682483;3605.963940360615;3625.2386998730035;3632.2372301816913;3655.469956856241;1067.572970811666;1089.477051015517;1091.645215305029;1158.7682463507163;1217.0993464836135;30937.535936960965;31395.686043139278;31505.684943150274;31925.68074319227;32106.83946580252;1077.6282182646974;1144.8511693479795;1179.4810283475244;1182.5742732616252;1237.7957636989795;14897.22610257438;17618.119094045247;17748.822511774975;18305.633887322445;18561.25754969813;319905;327633;328754;392752;414499;59741;60547.39452605417;60828.391716083155;60933.695331523944;61100;1271.910966232355;1330.5143622577882;1385.6051025457696;1412.2867951684334;1444.5377479206552;31406.52890206603;33606.49590256099;34141;36635.618684785404;36688.44967325439;56703.43296566982;57241.42758572527;57484;57530.42469575418;57561;462340.75318494113;465331;466178;466544;467109;27565.448691025667;28231.71768282291;30434.543481847348;30804.38391232118;48603.5139648599;46523.76738116288;50468.49531504785;50521.74739126281;51030.74484627553;51102;41778.79110604428;42304;42412;43407.56592434035;46827.765861170476;42485.362719558616;42987.570124298356;43196.78401607972;44653.77673111744;45313.320300194864;43920.78039609781;44050.118997619225;45338;45363.31955020611;45923.540764591926;36185.27629447449;38361.23277534489;38448.423273651475;39403;39640.20719585534;29202.561961571017;29496.262593435335;29498.705012950453;29545.409091818474;29877.253068673457 09:49:12 INFO - PROCESS | 9897 | |2;jquery.html;392.28799728492766;413.59674317540384;414.7428594271551;418.2200196633279;429.9914001719966;14398.640033999156;16118.274677639505;19662.606747865033;20179.495512612157;20281.69577456339;20228.08973596188;20266.392008239738;20450.59098818023;20465.693014604793;20881.58236835266;5675.772969081232;5718.656880587178;5817.534597232232;5863.648181109126;5984.072468767326;4316.352547117926;4546.568076032788;5768.04827203513;5868.325142608612;5868.853278668024;6223.626582405071;6488.448481879022;6516.9674151629415;6620.569662971916;6620.966895165541;6432.678366081701;6603.108580341641;6624.238212605515;6629.966850165718;6635.336466353374;5705.686187259741;5899.410058994066;5903.586748927577;5905.4980326672285;5908.438698323653;23505.882470587538;23858.642120368208;23906;24472.265832025067;24911.252662420156;2476.925692229236;2519.1057174703083;2611.8824652890476;2740.0135951057605;2870.6842247352774;14823.77764333536;15373.615659608487;15468.381264749396;15468.458603948808;15657.138857362841;1934.564722937342;1981.0391959899437;1993.9601207975759;1994.5312851480035;1999.180336062222;64340.67829660868;79044.60477697632;80290.19709802886;84406;88367.99999999936;11292.943535282435;11447.713807154887;11648.650540483799;11660;11885.524579016743;16025;16036.358545658277;16050.117243551716;16052.598685032968;16080.195990200444;8580.184882436284;8754.21212090915;8780.604872780741;8871.556422178865;9008.59461324242;8300.792480188044;8326.45878017927;8581.528015959182;8614.22471977525;8742.431741936754;6968.44252459802;7006;7024.192217895007;7049.753258635923;7084.681189346492;1970.862039657239;2024.1093918675735;2050.6718924971933;2053.712480252766;2054.897255137237;943.7593413679468;1010.3281317923645;1012.9848052279222;1099.587654629514;1111.050052205369;20296.797032029786;20968.79031209699;21343.67984480234;21417.78582214158;21482.57034859294;1046.7435478307864;1057.4025675493322;1092.6612750047511;1112.1769890281134;1149.9827502587468;11673.591424300108;12008.939955300168;12012.519499219934;12058.638240852788;12243.32661703615;467129;467433;471988;472086;474334;88983.55508222418;89416.55291723499;91157.54421227852;91267.54366228127;91528;1005.788784355279;1024.293237666008;1028.8971102889652;1043.3896170740202;1057.3708643357345;18175.909120454315;18534.536636584195;18557.721634175767;18565.628687426444;18842.281211446094;41767.58232417759;42065.57934420617;42190.57809421867;42457.787711061246;43065.56934430741;54369.72815135899;54727.452725472234;55506.44493555013;55706;65840.34159658341;5670.092785154356;7193.784186474414;8182.018157820861;8779.25376343018;9473.289503287144;12446.688832779253;14193.503227386991;14236.145831250158;14252.28738563047;14364.497242596459;12052.276863388224;12106;12198;12543.24740515572;12659.113862029937;12132.393380330772;12245.387730613256;12411.875881241072;12521.373931303217;12742.554010609585;12254.877451225373;12383.69040773988;12419.565315214286;12539.498420063095;12561.63535823386;8907.287417006495;9057.501837398742;9194.724158275263;9383.718488445085;9618;9507.809843802946;11660.591879284184;11921.403929803648;12417.503299867903;12488.313142776871 09:49:12 INFO - PROCESS | 9897 | |3;mootools.html;1335.4591390486844;1482.5774654223544;1505.7440235160022;1630.9021458712477;1784.5360206346338;7697.2302769723;7966.880496792553;8365.832683346345;8384.538850363235;8572.314214862812;8421.78945526362;8485.490870547772;8488.61801218945;8491.91508084919;8497.107803680601;2468.6050231962936;2500.599904015358;2501.4246723253573;2502.336880726609;2507.636392723054;2424.0788500369836;2495.214007587604;2500.1249562653106;2505.448801263719;2506.097804790274;2405.7113146422366;2420.4190994161363;2441.060191826151;2441.8046556275453;2442.7312995570474;2375.8336916415856;2378.7859092681656;2389.0443822470975;2391.916282930089;2411.9035238590436;2384.940376490584;2388.164142550111;2400.8919598618104;2402.6396040593877;2421.3825474503888;8444.57777111148;8478.448900821488;8766.035736069027;8882.245009174228;8883.20051195389;5357;5572.080606699889;5575.944240557624;5582.246396736429;5582.720863956786;3332.4001679697644;3405.9829700851583;3450.8102054387;3458.8789392371145;3479.669431404038;1030.567161792049;1063.136800544995;1075.7902209069157;1098.4233277529281;1098.7967226063124;12271.325077120755;12320.445579948924;12333.814992775115;12378.62864114069;12398.132130750852;4263.59495847894;4549.795259213277;4590.311453282035;4609.976950115295;4636.095961287584;9817.803643927082;9832.262580306506;9834.459104749307;9834.75413114678;9836.458994755214;5286.920696189561;5287.154055351125;5326.813561525328;5330.600204984642;5330.760115794798;4327.437433133736;4401.493828209796;4952;5021.974890125599;5037.496250374933;3930.5872883346838;3974.503187101612;4037.9192416151513;4079.2453396121814;4079.347304431277;1179.6932797472548;1198.4367347346733;1204.4158583087196;1208.6615747590688;1215.6778453709774;989.9851502227473;1009.7425156585024;1033.7415646088477;1035.9015893490107;1038.7039693687263;6174.783882564178;6256.017800216603;6323.241211054611;6494.480441564663;6531.281559028503;458.26677316293666;478.12741746313117;479.6690283704233;480.75962018990504;497.6615901187155;4452.414013566032;4478.820847166142;4487.394201782718;4571.81712731487;4602.332661764092;11002.559897604166;11219.495122719498;11233.606823761129;11235.438228088562;11267.492962816694;10381.584736610452;10808.837867431996;10988.890111098946;11126.054285385833;11133;8328.167183281623;8505.319574433906;8526.232639062515;8534.189252021048;8535.061143274235;6262.778758142119;6274.278457977391;6275.309715931153;6382.489400847828;6523.217213934358;10245.282830201815;10269.53787079598;10291.22815788804;10336.0697537222;10362.274640775073;10274.178065754571;10522.053015228666;10613.363198208132;10626.628068017582;10634.361938283728;6996.0905082341005;7222.96388518054;7360.815979600553;8077.1115177329275;8203.466774659628;8401.075881653136;8740.737777866674;8772.649094036167;8784.297256219359;8786.077461866413;7766.961165194138;7885.053793544811;7909.406794490551;7923.762287131395;8079.636416361395;7143.142822861082;7237.927620723725;7342.559446433231;7417.592032438268;7498.512596681198;7082.5042247044275;7154.034205382207;7180.7845764627145;7188;7188.28117188277;5415.08680902025;5602.411746766694;5602.963451761437;5628.802991895428;5703.800366987136;5314.22943673173;5448.645838020516;5511.063119269814;5512.3385193777;5514 09:49:12 INFO - PROCESS | 9897 | |4;prototype.html;340.0461704917706;340.16828853453285;341.61227007346656;343.72501998401276;351.5798620648325;5169.896602067956;5212.872276634018;5225.425203227642;5273.839755253838;5283.471652834714;5203.817866374678;5230.189320655304;5233.73831308435;5237.528622423991;5245.501677340647;4177.326511032941;4187.225363307783;4206.705530612859;4210.936835947463;4213.4522512073545;3714;3972.9205415891665;4023.275810354135;4033.495813023372;4110.691698122638;4061.614146656063;4087.9182416351655;4089.1821635672836;4093.9385909211237;4101.938470922939;4098.8360465581345;4106.733062350938;4107.753534787892;4109.198706252282;4118.546959834416;3524.559430071241;3603.3333833240936;3611.3860643690373;3680.834362453696;3682.926341473182;5059.254557177768;5084.771185296633;5092.6180536459915;5096.731954280347;5098.898022039575;4727.479977202505;4736.360591320162;4740.217864052462;4747.596454301354;4760.262159365304;5191.871332753553;5423.454315520058;5454.318210223722;5470.343558772933;5472.288602481652;4012.1574469361467;4014.0165659413337;4017.5336002358927;4019.7588144711117;4025.9597404025876;5394.298741163623;5483.369412517532;5539.25220095286;5660.235868157828;5701.971490142523;4065.4714887064492;4068.4710987572016;4072.6334629883454;4100.958990410118;4106.958930410718;4100.7334523255895;4128.669706423479;4130.822715526121;4135.648469880094;4137.420761093449;4552.385427967249;4674.644353137552;4709.788059537329;4714.302850973647;4722.6221902247735;5417.972910135503;5439.864003399867;5452.40023597404;5469.945300547023;5495.972520137454;1350.540816122523;1365.938532766028;1366.371469124214;1366.760816857046;1369.7465968795805;917.6062114882095;960.7454024683465;966.5215718219526;969.8399764038995;973.8393165127816;5124.666896651705;5143.922841157386;5144.768485418165;5167.819126330561;5213.530782229617;5364.436734142859;5386.875612144947;5390;5405;5405.729713514309;5054.671446355975;5110.2590124431745;5182.844514664566;5195.428263009834;5205.765740541685;2847.601335812988;2860.3278229616026;2867.641544806899;2868.756155726745;2869.9282517937218;5543.916841247385;5556.805511807148;5563.610547261734;5604.3835178130375;5616.522595579339;5425.240466334716;5464.207689884945;5704.744956109649;5712.914306285409;5747.8563035924435;5853.019946436464;5882.705864706748;5897.085951677411;5901;5909.409059094056;5500.477454641803;5605.607607467438;5626.611243230287;5642.802501912413;5644.85887852807;5532.5297349724115;5550.171350924721;5566.304211973504;5568.415316391883;5569.66582005081;5600.299962504687;5708.65748055118;5720.427957204246;5869.618474799125;5905.940940590711;4115.670746340225;4135.937960930528;4136.751794892316;4140.399642051946;4144.647704945113;5447.346318441812;5556;5649.71751412436;5651.773929042956;5653.830385088454;5378.542823860016;5408.432114627907;5482.725863706878;5493.818828951795;5497.780088796403;5433.782648694008;5454.427285135003;5478.863028424321;5489.58828087887;5503.972480137573;5220.817271395483;5383.1117865553315;5384.865378365572;5416;5430.212619170119;5442.972785136049;5457.481539253765;5464.508194262536;5466.234727138124;5473.72631368422;5354;5363.839084827461;5384.542313903362;5385.865353366197;5394.487523685244 09:49:12 INFO - PROCESS | 9897 | |5;yui.html;789.5420656019505;803.036356372353;819.5082950229862;842.3489959679029;884.0408157149493;15124.697506049872;16113.597160071004;16704.248308826103;16866;16948.745768813445;15266;15351.769723454157;15702.921485392557;16278.75581866273;16434.671306573862;729.6534146280527;858.6093327535956;877.0703054761941;877.745453818395;879.9780005499849;726.1757904778088;735.0774777654036;735.5549892315153;815.6003558256441;861.8362511122898;911.9270458363314;918.4351623751378;918.4489306416131;919.1130559010553;919.462114662923;905.7690288976302;907.2515174980667;907.773056735816;908.3232991421401;908.6047569307307;552.7429745168516;576.2652617912154;621.6239175298924;672.865426914619;673.2123415603755;15019.399224031025;15240.314185861664;15740.685186296325;15745.370185192578;15761.448349307833;13660.863391366158;13862.792058119136;14057.64855878601;14184.574462766133;14318.069325493914;10886.346819190874;13004.499982519423;13208.933955330258;13485.932570337183;13533.458661653522;3940.7635541867294;4506.256467682829;4545.0909818036525;4605.861824145281;4691.953080469186;60367;69043.65478172628;69060.61878762397;71194;71409.64295178543;11987.940060299643;12062.215955963009;12114.333711645768;12157.088218383657;12572;20206.191752330036;20234.595308093754;21672.13311467555;21975;22299.55400891973;7901.762947111596;8169.101398846122;8607.784805379915;8646.52444115567;8827.301260971839;4804.495527969513;4846.5638092571135;4904.754762261872;4965.677230980047;4967.180415231519;3510.280392519527;3592.2276710507367;3593.3711600470338;3631.382664946965;3637.5816781070516;629.8464006655046;641.1824923222835;657.086649557116;657.3853447027045;664.9700763465559;602.9125776762345;679.6805501414327;738.8596166728412;775.7827808213709;787.3661702329565;9563.474008929437;10031.846337671115;10245.641402550875;10704.571817127227;10707.518161682743;661.867626474707;683.3781259054336;733.9119305683353;777.7472321495536;792.2513225002317;4563.406757121553;4795.859975617589;4852.539008794159;4878.341423907798;4930.408350997904;84929.57535212285;88447;89537;90086.09913900908;94505.5274723622;67175;68635.65682171557;74421.25578744251;75011.24988750152;79431.60284198543;68268.6586567074;69088.6545567279;70653;71091.93362099573;71416.285837142;10020.799584008424;10063.49682515886;10159.339642923187;10431.453305876039;10656.360618362924;34818.129546761535;34968.82515587406;45028.588549598215;46909.29636055393;49069;63156.368436315046;64591;65414.67292663506;66555.33444665685;66986.6650666763;12370.381480926098;12844.743105138032;12870.935645321713;13092.80360794608;13439.529616463376;14235.50175743844;14342.641433964234;14815.777763333774;15204.923975380052;16056.518304450885;13326.93336533311;13639.590812275648;13672.248026358646;13830.93084534611;13979.301034948416;13407.664808379868;13506.392212350664;13514.729705406033;13823.723525529233;13984.44062237499;13030.869691302965;13051.804222936853;13264.867351326364;13383.933080334536;13606.523771667944;11653.417329133339;11730.88269117298;11859.644210673694;11925.105617078927;12209.572664956684;11765.470553824927;11942.34317112567;11960.162788604714;12046.65012406948;12178.878211218129 09:49:12 INFO - PROCESS | 9897 | __end_tp_report 09:49:12 INFO - PROCESS | 9897 | __start_cc_report 09:49:12 INFO - PROCESS | 9897 | _x_x_mozilla_cycle_collect,996 09:49:12 INFO - PROCESS | 9897 | __end_cc_report 09:49:12 INFO - PROCESS | 9897 | __startTimestamp1483465752622__endTimestamp 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | ------- Summary: start ------- 09:49:12 INFO - PROCESS | 9897 | Number of tests: 6 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#0] dojo.html Cycles:165 Average:5390.71 Median:5617.41 stddev:583.04 (10.4%) stddev-sans-first:558.30 09:49:12 INFO - PROCESS | 9897 | Values: 3174.2 3209.2 3215.4 3239.5 3252.4 5346.4 5377.4 5394.2 5409.1 5416.8 5490.9 5494.0 5494.2 5516.2 5520.7 4969.9 4987.1 5012.2 5043.3 5052.5 4833.1 4856.2 4874.5 5020.0 5081.4 4871.0 4881.8 4895.2 4964.2 4984.3 4984.6 4997.1 5001.9 5006.4 5029.4 4809.5 4908.5 4934.0 4950.4 4974.1 5237.8 5274.7 5284.6 5353.3 5360.4 5637.9 5644.6 5645.4 5716.3 5730.5 5743.6 5763.0 5798.5 5800.1 5803.2 5308.6 5345.5 5348.3 5378.4 5443.1 5909.1 5918.4 5918.9 5921.9 5925.3 5688.8 5694.5 5695.1 5705.9 5706.4 5606.0 5654.3 5694.0 5718.1 5725.0 5781.7 5788.8 5789.7 5790.5 5823.6 5773.9 5780.4 5794.6 5797.6 5798.1 4385.0 4418.5 4451.8 4505.5 4542.7 4092.0 4103.5 4115.3 4123.2 4144.9 5570.3 5617.4 5620.1 5625.1 5628.0 5592.6 5643.7 5650.6 5869.1 5873.0 5507.2 5555.2 5558.1 5601.6 5632.8 5076.5 5089.1 5090.6 5091.3 5119.0 5931.7 6097.2 6143.6 6169.3 6173.2 5678.8 5690.9 5715.4 5733.4 5821.2 6042.3 6048.0 6098.8 6101.8 6124.1 5705.7 5710.7 5711.4 5713.8 5715.1 5571.9 5595.3 5699.0 5753.4 5760.3 5629.9 5635.7 5644.0 5703.3 5724.7 5618.7 5623.0 5626.8 5627.9 5632.4 5394.9 5448.6 5525.1 5551.4 5629.2 5706.2 5732.6 5758.8 5759.9 5788.7 5353.4 5606.8 5616.1 5617.0 5620.4 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#1] ext.html Cycles:180 Average:56333.97 Median:20551.79 stddev:104412.42 (508.0%) stddev-sans-first:104621.42 09:49:12 INFO - PROCESS | 9897 | Values: 583.5 614.2 639.0 646.8 665.6 34059.0 44960.8 45800.0 45917.5 46026.5 46105.8 46330.5 46475.0 46503.5 46560.8 8413.2 8777.3 8904.9 8914.6 8917.4 8574.0 10529.2 10779.4 10796.0 11080.6 10812.2 10869.3 10878.0 10881.2 10882.5 10661.9 10712.0 10721.8 10729.3 10742.4 10606.4 10683.4 10688.5 10689.4 10690.9 42724.8 43001.0 43010.6 43017.8 43092.4 3629.6 3666.9 3695.8 3869.0 4096.0 32166.0 32422.2 32599.8 32722.3 32852.3 4345.2 4382.6 4383.6 4398.4 4468.2 351254.0 353631.0 353771.9 354444.0 354517.0 37407.8 58912.7 59366.7 59383.4 59384.4 38613.8 40736.2 40837.0 40882.0 40910.0 16780.1 16951.2 16956.6 16966.0 16979.4 12883.4 13120.7 13778.9 14040.0 14179.6 9148.3 9297.6 9343.9 9393.2 9404.9 3554.5 3606.0 3625.2 3632.2 3655.5 1067.6 1089.5 1091.6 1158.8 1217.1 30937.5 31395.7 31505.7 31925.7 32106.8 1077.6 1144.9 1179.5 1182.6 1237.8 14897.2 17618.1 17748.8 18305.6 18561.3 319905.0 327633.0 328754.0 392752.0 414499.0 59741.0 60547.4 60828.4 60933.7 61100.0 1271.9 1330.5 1385.6 1412.3 1444.5 31406.5 33606.5 34141.0 36635.6 36688.4 56703.4 57241.4 57484.0 57530.4 57561.0 462340.8 465331.0 466178.0 466544.0 467109.0 27565.4 28231.7 30434.5 30804.4 48603.5 46523.8 50468.5 50521.7 51030.7 51102.0 41778.8 42304.0 42412.0 43407.6 46827.8 42485.4 42987.6 43196.8 44653.8 45313.3 43920.8 44050.1 45338.0 45363.3 45923.5 36185.3 38361.2 38448.4 39403.0 39640.2 29202.6 29496.3 29498.7 29545.4 29877.3 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#2] jquery.html Cycles:180 Average:28932.84 Median:24189.13 stddev:77529.90 (320.5%) stddev-sans-first:77717.77 09:49:12 INFO - PROCESS | 9897 | Values: 392.3 413.6 414.7 418.2 430.0 14398.6 16118.3 19662.6 20179.5 20281.7 20228.1 20266.4 20450.6 20465.7 20881.6 5675.8 5718.7 5817.5 5863.6 5984.1 4316.4 4546.6 5768.0 5868.3 5868.9 6223.6 6488.4 6517.0 6620.6 6621.0 6432.7 6603.1 6624.2 6630.0 6635.3 5705.7 5899.4 5903.6 5905.5 5908.4 23505.9 23858.6 23906.0 24472.3 24911.3 2476.9 2519.1 2611.9 2740.0 2870.7 14823.8 15373.6 15468.4 15468.5 15657.1 1934.6 1981.0 1994.0 1994.5 1999.2 64340.7 79044.6 80290.2 84406.0 88368.0 11292.9 11447.7 11648.7 11660.0 11885.5 16025.0 16036.4 16050.1 16052.6 16080.2 8580.2 8754.2 8780.6 8871.6 9008.6 8300.8 8326.5 8581.5 8614.2 8742.4 6968.4 7006.0 7024.2 7049.8 7084.7 1970.9 2024.1 2050.7 2053.7 2054.9 943.8 1010.3 1013.0 1099.6 1111.1 20296.8 20968.8 21343.7 21417.8 21482.6 1046.7 1057.4 1092.7 1112.2 1150.0 11673.6 12008.9 12012.5 12058.6 12243.3 467129.0 467433.0 471988.0 472086.0 474334.0 88983.6 89416.6 91157.5 91267.5 91528.0 1005.8 1024.3 1028.9 1043.4 1057.4 18175.9 18534.5 18557.7 18565.6 18842.3 41767.6 42065.6 42190.6 42457.8 43065.6 54369.7 54727.5 55506.4 55706.0 65840.3 5670.1 7193.8 8182.0 8779.3 9473.3 12446.7 14193.5 14236.1 14252.3 14364.5 12052.3 12106.0 12198.0 12543.2 12659.1 12132.4 12245.4 12411.9 12521.4 12742.6 12254.9 12383.7 12419.6 12539.5 12561.6 8907.3 9057.5 9194.7 9383.7 9618.0 9507.8 11660.6 11921.4 12417.5 12488.3 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#3] mootools.html Cycles:180 Average:5860.27 Median:4623.04 stddev:3297.73 (71.3%) stddev-sans-first:3289.45 09:49:12 INFO - PROCESS | 9897 | Values: 1335.5 1482.6 1505.7 1630.9 1784.5 7697.2 7966.9 8365.8 8384.5 8572.3 8421.8 8485.5 8488.6 8491.9 8497.1 2468.6 2500.6 2501.4 2502.3 2507.6 2424.1 2495.2 2500.1 2505.4 2506.1 2405.7 2420.4 2441.1 2441.8 2442.7 2375.8 2378.8 2389.0 2391.9 2411.9 2384.9 2388.2 2400.9 2402.6 2421.4 8444.6 8478.4 8766.0 8882.2 8883.2 5357.0 5572.1 5575.9 5582.2 5582.7 3332.4 3406.0 3450.8 3458.9 3479.7 1030.6 1063.1 1075.8 1098.4 1098.8 12271.3 12320.4 12333.8 12378.6 12398.1 4263.6 4549.8 4590.3 4610.0 4636.1 9817.8 9832.3 9834.5 9834.8 9836.5 5286.9 5287.2 5326.8 5330.6 5330.8 4327.4 4401.5 4952.0 5022.0 5037.5 3930.6 3974.5 4037.9 4079.2 4079.3 1179.7 1198.4 1204.4 1208.7 1215.7 990.0 1009.7 1033.7 1035.9 1038.7 6174.8 6256.0 6323.2 6494.5 6531.3 458.3 478.1 479.7 480.8 497.7 4452.4 4478.8 4487.4 4571.8 4602.3 11002.6 11219.5 11233.6 11235.4 11267.5 10381.6 10808.8 10988.9 11126.1 11133.0 8328.2 8505.3 8526.2 8534.2 8535.1 6262.8 6274.3 6275.3 6382.5 6523.2 10245.3 10269.5 10291.2 10336.1 10362.3 10274.2 10522.1 10613.4 10626.6 10634.4 6996.1 7223.0 7360.8 8077.1 8203.5 8401.1 8740.7 8772.6 8784.3 8786.1 7767.0 7885.1 7909.4 7923.8 8079.6 7143.1 7237.9 7342.6 7417.6 7498.5 7082.5 7154.0 7180.8 7188.0 7188.3 5415.1 5602.4 5603.0 5628.8 5703.8 5314.2 5448.6 5511.1 5512.3 5514.0 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#4] prototype.html Cycles:180 Average:4602.08 Median:5227.81 stddev:1336.58 (25.6%) stddev-sans-first:1301.48 09:49:12 INFO - PROCESS | 9897 | Values: 340.0 340.2 341.6 343.7 351.6 5169.9 5212.9 5225.4 5273.8 5283.5 5203.8 5230.2 5233.7 5237.5 5245.5 4177.3 4187.2 4206.7 4210.9 4213.5 3714.0 3972.9 4023.3 4033.5 4110.7 4061.6 4087.9 4089.2 4093.9 4101.9 4098.8 4106.7 4107.8 4109.2 4118.5 3524.6 3603.3 3611.4 3680.8 3682.9 5059.3 5084.8 5092.6 5096.7 5098.9 4727.5 4736.4 4740.2 4747.6 4760.3 5191.9 5423.5 5454.3 5470.3 5472.3 4012.2 4014.0 4017.5 4019.8 4026.0 5394.3 5483.4 5539.3 5660.2 5702.0 4065.5 4068.5 4072.6 4101.0 4107.0 4100.7 4128.7 4130.8 4135.6 4137.4 4552.4 4674.6 4709.8 4714.3 4722.6 5418.0 5439.9 5452.4 5469.9 5496.0 1350.5 1365.9 1366.4 1366.8 1369.7 917.6 960.7 966.5 969.8 973.8 5124.7 5143.9 5144.8 5167.8 5213.5 5364.4 5386.9 5390.0 5405.0 5405.7 5054.7 5110.3 5182.8 5195.4 5205.8 2847.6 2860.3 2867.6 2868.8 2869.9 5543.9 5556.8 5563.6 5604.4 5616.5 5425.2 5464.2 5704.7 5712.9 5747.9 5853.0 5882.7 5897.1 5901.0 5909.4 5500.5 5605.6 5626.6 5642.8 5644.9 5532.5 5550.2 5566.3 5568.4 5569.7 5600.3 5708.7 5720.4 5869.6 5905.9 4115.7 4135.9 4136.8 4140.4 4144.6 5447.3 5556.0 5649.7 5651.8 5653.8 5378.5 5408.4 5482.7 5493.8 5497.8 5433.8 5454.4 5478.9 5489.6 5504.0 5220.8 5383.1 5384.9 5416.0 5430.2 5443.0 5457.5 5464.5 5466.2 5473.7 5354.0 5363.8 5384.5 5385.9 5394.5 09:49:12 INFO - PROCESS | 9897 | 09:49:12 INFO - PROCESS | 9897 | [#5] yui.html Cycles:180 Average:18394.82 Median:4584.63 stddev:23732.61 (517.7%) stddev-sans-first:23762.37 09:49:12 INFO - PROCESS | 9897 | Values: 789.5 803.0 819.5 842.3 884.0 15124.7 16113.6 16704.2 16866.0 16948.7 15266.0 15351.8 15702.9 16278.8 16434.7 729.7 858.6 877.1 877.7 880.0 726.2 735.1 735.6 815.6 861.8 911.9 918.4 918.4 919.1 919.5 905.8 907.3 907.8 908.3 908.6 552.7 576.3 621.6 672.9 673.2 15019.4 15240.3 15740.7 15745.4 15761.4 13660.9 13862.8 14057.6 14184.6 14318.1 10886.3 13004.5 13208.9 13485.9 13533.5 3940.8 4506.3 4545.1 4605.9 4692.0 60367.0 69043.7 69060.6 71194.0 71409.6 11987.9 12062.2 12114.3 12157.1 12572.0 20206.2 20234.6 21672.1 21975.0 22299.6 7901.8 8169.1 8607.8 8646.5 8827.3 4804.5 4846.6 4904.8 4965.7 4967.2 3510.3 3592.2 3593.4 3631.4 3637.6 629.8 641.2 657.1 657.4 665.0 602.9 679.7 738.9 775.8 787.4 9563.5 10031.8 10245.6 10704.6 10707.5 661.9 683.4 733.9 777.7 792.3 4563.4 4795.9 4852.5 4878.3 4930.4 84929.6 88447.0 89537.0 90086.1 94505.5 67175.0 68635.7 74421.3 75011.2 79431.6 68268.7 69088.7 70653.0 71091.9 71416.3 10020.8 10063.5 10159.3 10431.5 10656.4 34818.1 34968.8 45028.6 46909.3 49069.0 63156.4 64591.0 65414.7 66555.3 66986.7 12370.4 12844.7 12870.9 13092.8 13439.5 14235.5 14342.6 14815.8 15204.9 16056.5 13326.9 13639.6 13672.2 13830.9 13979.3 13407.7 13506.4 13514.7 13823.7 13984.4 13030.9 13051.8 13264.9 13383.9 13606.5 11653.4 11730.9 11859.6 11925.1 12209.6 11765.5 11942.3 11960.2 12046.7 12178.9 09:49:12 INFO - PROCESS | 9897 | -------- Summary: end -------- 09:49:12 INFO - PROCESS | 9897 | 09:49:13 INFO - TEST-INFO | 9897: exit 0 09:49:13 INFO - TEST-OK | dromaeo_css | took 1115888ms 09:49:13 INFO - TEST-START | kraken 09:49:13 INFO - operating with platform_type : linux_ 09:49:13 INFO - Initialising browser for kraken test... 09:49:13 INFO - TEST-INFO | started process 10221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPPS520/profile http://localhost:58563/getInfo.html) 09:49:17 INFO - PROCESS | 10221 | __metrics Screen width/height:1600/1200 09:49:17 INFO - PROCESS | 10221 | colorDepth:24 09:49:17 INFO - PROCESS | 10221 | Browser inner width/height: 1024/697 09:49:17 INFO - PROCESS | 10221 | __metrics 09:49:18 INFO - PROCESS | 10221 | 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" 09:49:18 INFO - PROCESS | 10221 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:18 INFO - PROCESS | 10221 | console.error: PushService: 09:49:18 INFO - PROCESS | 10221 | stateChangeProcessEnqueue: Error transitioning state 09:49:18 INFO - PROCESS | 10221 | UnknownError 09:49:19 INFO - TEST-INFO | 10221: exit 0 09:49:19 INFO - Browser initialized. 09:49:19 INFO - Running cycle 1/1 for kraken test... 09:49:19 INFO - TEST-INFO | started process 10315 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPPS520/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:31 INFO - PROCESS | 10315 | RSS: Main: 147869696 09:49:31 INFO - PROCESS | 10315 | 09:49:33 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58563/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:34 INFO - PROCESS | 10315 | RSS: Main: 188620800 09:49:34 INFO - PROCESS | 10315 | 09:49:37 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58563/tests/kraken/driver.html?testName=audio-dft) 09:49:37 INFO - PROCESS | 10315 | RSS: Main: 178610176 09:49:37 INFO - PROCESS | 10315 | 09:49:40 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58563/tests/kraken/driver.html?testName=audio-fft) 09:49:41 INFO - PROCESS | 10315 | RSS: Main: 270401536 09:49:41 INFO - PROCESS | 10315 | 09:49:43 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58563/tests/kraken/driver.html?testName=audio-oscillator) 09:49:43 INFO - PROCESS | 10315 | RSS: Main: 176545792 09:49:43 INFO - PROCESS | 10315 | 09:49:45 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58563/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:49:46 INFO - PROCESS | 10315 | RSS: Main: 179429376 09:49:46 INFO - PROCESS | 10315 | 09:49:49 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58563/tests/kraken/driver.html?testName=imaging-darkroom) 09:49:49 INFO - PROCESS | 10315 | RSS: Main: 217956352 09:49:49 INFO - PROCESS | 10315 | 09:49:53 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58563/tests/kraken/driver.html?testName=imaging-desaturate) 09:49:53 INFO - PROCESS | 10315 | RSS: Main: 217075712 09:49:53 INFO - PROCESS | 10315 | 09:49:56 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58563/tests/kraken/driver.html?testName=json-parse-financial) 09:49:57 INFO - PROCESS | 10315 | RSS: Main: 215207936 09:49:57 INFO - PROCESS | 10315 | 09:49:58 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58563/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:59 INFO - PROCESS | 10315 | RSS: Main: 177102848 09:49:59 INFO - PROCESS | 10315 | 09:50:00 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:01 INFO - PROCESS | 10315 | RSS: Main: 184504320 09:50:01 INFO - PROCESS | 10315 | 09:50:03 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:03 INFO - PROCESS | 10315 | RSS: Main: 190074880 09:50:03 INFO - PROCESS | 10315 | 09:50:06 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:07 INFO - PROCESS | 10315 | RSS: Main: 198901760 09:50:07 INFO - PROCESS | 10315 | 09:50:09 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:10 INFO - PROCESS | 10315 | RSS: Main: 195428352 09:50:10 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | Cycle 1(1): loaded http://localhost:58563/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58563/tests/kraken/driver.html?testName=ai-astar) 09:50:12 INFO - PROCESS | 10315 | RSS: Main: 247586816 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | __start_tp_report 09:50:12 INFO - PROCESS | 10315 | _x_x_mozilla_page_load 09:50:12 INFO - PROCESS | 10315 | _x_x_mozilla_page_load_details 09:50:12 INFO - PROCESS | 10315 | |i|pagename|runs| 09:50:12 INFO - PROCESS | 10315 | |0;ai-astar;104;112;111;112;112;113;112;112;112;111 09:50:12 INFO - PROCESS | 10315 | |1;audio-beat-detection;142;118;118;152;118;117;118;117;148;118 09:50:12 INFO - PROCESS | 10315 | |2;audio-dft;161;161;161;160;161;161;159;163;160;162 09:50:12 INFO - PROCESS | 10315 | |3;audio-fft;74;75;75;115;76;76;76;77;97;75 09:50:12 INFO - PROCESS | 10315 | |4;audio-oscillator;82;85;119;81;81;81;98;81;81;105 09:50:12 INFO - PROCESS | 10315 | |5;imaging-gaussian-blur;117;117;116;117;117;118;117;116;118;117 09:50:12 INFO - PROCESS | 10315 | |6;imaging-darkroom;146;146;146;146;146;147;146;146;147;147 09:50:12 INFO - PROCESS | 10315 | |7;imaging-desaturate;85;85;85;85;85;85;85;85;85;84 09:50:12 INFO - PROCESS | 10315 | |8;json-parse-financial;50;51;50;51;51;52;51;50;50;51 09:50:12 INFO - PROCESS | 10315 | |9;json-stringify-tinderbox;59;60;60;60;62;59;59;62;59;60 09:50:12 INFO - PROCESS | 10315 | |10;stanford-crypto-aes;75;75;75;75;75;75;76;76;74;82 09:50:12 INFO - PROCESS | 10315 | |11;stanford-crypto-ccm;125;139;126;132;119;130;119;119;173;140 09:50:12 INFO - PROCESS | 10315 | |12;stanford-crypto-pbkdf2;157;157;162;161;158;160;157;158;160;155 09:50:12 INFO - PROCESS | 10315 | |13;stanford-crypto-sha256-iterative;62;62;62;63;62;62;62;61;63;61 09:50:12 INFO - PROCESS | 10315 | __end_tp_report 09:50:12 INFO - PROCESS | 10315 | __start_cc_report 09:50:12 INFO - PROCESS | 10315 | _x_x_mozilla_cycle_collect,1620 09:50:12 INFO - PROCESS | 10315 | __end_cc_report 09:50:12 INFO - PROCESS | 10315 | __startTimestamp1483465812444__endTimestamp 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | ------- Summary: start ------- 09:50:12 INFO - PROCESS | 10315 | Number of tests: 14 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.56 (2.3%) stddev-sans-first:0.60 09:50:12 INFO - PROCESS | 10315 | Values: 104.0 112.0 111.0 112.0 112.0 113.0 112.0 112.0 112.0 111.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:118.00 stddev:14.51 (12.3%) stddev-sans-first:14.28 09:50:12 INFO - PROCESS | 10315 | Values: 142.0 118.0 118.0 152.0 118.0 117.0 118.0 117.0 148.0 118.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 09:50:12 INFO - PROCESS | 10315 | Values: 161.0 161.0 161.0 160.0 161.0 161.0 159.0 163.0 160.0 162.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:13.57 (17.9%) stddev-sans-first:14.11 09:50:12 INFO - PROCESS | 10315 | Values: 74.0 75.0 75.0 115.0 76.0 76.0 76.0 77.0 97.0 75.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:81.00 stddev:13.42 (16.6%) stddev-sans-first:13.96 09:50:12 INFO - PROCESS | 10315 | Values: 82.0 85.0 119.0 81.0 81.0 81.0 98.0 81.0 81.0 105.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:50:12 INFO - PROCESS | 10315 | Values: 117.0 117.0 116.0 117.0 117.0 118.0 117.0 116.0 118.0 117.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:50:12 INFO - PROCESS | 10315 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 147.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.32 (0.4%) stddev-sans-first:0.33 09:50:12 INFO - PROCESS | 10315 | Values: 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 84.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 09:50:12 INFO - PROCESS | 10315 | Values: 50.0 51.0 50.0 51.0 51.0 52.0 51.0 50.0 50.0 51.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.15 (1.9%) stddev-sans-first:1.17 09:50:12 INFO - PROCESS | 10315 | Values: 59.0 60.0 60.0 60.0 62.0 59.0 59.0 62.0 59.0 60.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.00 stddev:2.25 (3.0%) stddev-sans-first:2.37 09:50:12 INFO - PROCESS | 10315 | Values: 75.0 75.0 75.0 75.0 75.0 75.0 76.0 76.0 74.0 82.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:131.00 stddev:16.29 (12.4%) stddev-sans-first:17.07 09:50:12 INFO - PROCESS | 10315 | Values: 125.0 139.0 126.0 132.0 119.0 130.0 119.0 119.0 173.0 140.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.50 Median:159.00 stddev:2.17 (1.4%) stddev-sans-first:2.24 09:50:12 INFO - PROCESS | 10315 | Values: 157.0 157.0 162.0 161.0 158.0 160.0 157.0 158.0 160.0 155.0 09:50:12 INFO - PROCESS | 10315 | 09:50:12 INFO - PROCESS | 10315 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 09:50:12 INFO - PROCESS | 10315 | Values: 62.0 62.0 62.0 63.0 62.0 62.0 62.0 61.0 63.0 61.0 09:50:12 INFO - PROCESS | 10315 | -------- Summary: end -------- 09:50:12 INFO - PROCESS | 10315 | 09:50:13 INFO - TEST-INFO | 10315: exit 0 09:50:13 INFO - TEST-OK | kraken | took 60094ms 09:50:13 INFO - SUITE-END | took 1175s 09:50:13 INFO - Completed test suite (00:19:36) 09:50:13 INFO - javascript benchmark 09:50:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3174.1588479053057, 3209.197700574857, 3215.389076075544, 3239.465488194448, 3252.4470839957203, 5346.411894691586, 5377.408485066644, 5394.217838413428, 5409.107497262957, 5416.837494875155, 5490.890182196354, 5494.0, 5494.203340515623, 5516.172574113885, 5520.696361700105, 4969.875753106174, 4987.102321582114, 5012.172991456406, 5043.344365232515, 5052.545270925616, 4833.057553777015, 4856.150173719602, 4874.512548745132, 5019.974900125494, 5081.364829396325, 4870.977094810094, 4881.780319885614, 4895.241237608158, 4964.180910149821, 4984.302197692308, 4984.576311013569, 4997.125503036954, 5001.949980500185, 5006.374203224597, 5029.446760856303, 4809.470958194595, 4908.4846091160625, 4934.0, 4950.381202349707, 4974.079795237856, 5237.8428647140645, 5274.683518988873, 5284.5772338212855, 5353.250544923675, 5360.383555891044, 5637.943620563783, 5644.576656750719, 5645.407232240596, 5716.256886604715, 5730.484256416901, 5743.597948143632, 5763.049096899049, 5798.507126894219, 5800.100984347433, 5803.245578074865, 5308.628396012281, 5345.54562862161, 5348.304720386363, 5378.408375078739, 5443.074677304868, 5909.143174239711, 5918.408159184133, 5918.881622367615, 5921.940780592225, 5925.34821169671, 5688.829335119953, 5694.4590263924865, 5695.117256825199, 5705.914411283834, 5706.400827913092, 5605.990921634064, 5654.321481422256, 5694.032014557535, 5718.142278658234, 5724.998125328051, 5781.739821708034, 5788.797392091256, 5789.681567513827, 5790.478856902899, 5823.56323275756, 5773.88452230953, 5780.393058728857, 5794.623349482355, 5797.594168408171, 5798.10129429939, 4385.0, 4418.513963464016, 4451.844185453494, 4505.549445055468, 4542.659300552472, 4092.0, 4103.466549348565, 4115.279826030493, 4123.237201117803, 4144.91710165795, 5570.275864137636, 5617.41017193197, 5620.100783874642, 5625.128105143709, 5627.971860140673, 5592.5805564582815, 5643.661380317194, 5650.604457688005, 5869.06095024794, 5873.0, 5507.173923911445, 5555.2222688823595, 5558.082916318843, 5601.60788744784, 5632.774689012475, 5076.517730815566, 5089.083964886282, 5090.592752579785, 5091.338126043591, 5118.974405128025, 5931.703414829155, 6097.207363042865, 6143.631382117087, 6169.321374648785, 6173.197484327009, 5678.829635110953, 5690.9430905690415, 5715.371309155907, 5733.426657334233, 5821.155932389865, 6042.335343112342, 6048.032314829607, 6098.847528811815, 6101.816945491642, 6124.050772130327, 5705.686187259741, 5710.714464276686, 5711.400302968129, 5713.800016999385, 5715.057015592547, 5571.8885622288135, 5595.328560572757, 5698.971505142614, 5753.4246575343805, 5760.308762948473, 5629.930313240471, 5635.718214089197, 5644.012297847778, 5703.344115426779, 5724.656520608777, 5618.6628802272, 5622.959752445647, 5626.831195064155, 5627.915581266366, 5632.352279487911, 5394.892102158014, 5448.618596698193, 5525.143602741581, 5551.361593416808, 5629.211910332475, 5706.2011318414625, 5732.57005724564, 5758.827235182951, 5759.94240057594, 5788.652680839163, 5353.357597088218, 5606.775728970959, 5616.129499927518, 5617.0, 5620.437956204347], "value": 5353.647781335892, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.4981915552625, 614.1739360560047, 638.996805015975, 646.7994921574315, 665.5640555436185, 34059.0, 44960.775196124014, 45799.999999999956, 45917.54082459174, 46026.539734602644, 46105.76947115268, 46330.53669463305, 46475.0, 46503.534964650346, 46560.76719616406, 8413.200745929127, 8777.253933415668, 8904.8664270036, 8914.598843052077, 8917.42036767608, 8573.95713021434, 10529.157667386591, 10779.407132607703, 10796.028357447829, 11080.556777728882, 10812.189085818594, 10869.293495922779, 10878.0, 10881.183911206652, 10882.510287037063, 10661.893381066206, 10711.982361675628, 10721.785564288748, 10729.302595331279, 10742.355458672426, 10606.363618182857, 10683.412412317319, 10688.465576721133, 10689.358638481715, 10690.946545267223, 42724.78637606823, 43001.0, 43010.56989430128, 43017.784911075556, 43092.35361469581, 3629.5825980012373, 3666.8899933002053, 3695.7597756146033, 3869.0, 4096.045197740111, 32166.0, 32422.189445264055, 32599.83700081508, 32722.345553089042, 32852.342953141044, 4345.1961387143165, 4382.583654552813, 4383.561643835623, 4398.3842262083335, 4468.173387923244, 351254.0, 353631.0, 353771.9245615099, 354444.0, 354516.99999999744, 37407.81296093502, 58912.70543647254, 59366.70316648475, 59383.40616593865, 59384.40615593875, 38613.80693096573, 40736.18527629431, 40837.0, 40882.0, 40910.0, 16780.077095759607, 16951.237194326284, 16956.576085597713, 16966.0, 16979.405720799714, 12883.35583220854, 13120.737585248242, 13778.931105344409, 14040.0, 14179.574612761633, 9148.313876459291, 9297.628094876265, 9343.859842102374, 9393.154616084585, 9404.905950940403, 3554.5023696682483, 3605.963940360615, 3625.2386998730035, 3632.2372301816913, 3655.469956856241, 1067.572970811666, 1089.477051015517, 1091.645215305029, 1158.7682463507163, 1217.0993464836135, 30937.535936960965, 31395.686043139278, 31505.684943150274, 31925.68074319227, 32106.83946580252, 1077.6282182646974, 1144.8511693479795, 1179.4810283475244, 1182.5742732616252, 1237.7957636989795, 14897.22610257438, 17618.119094045247, 17748.822511774975, 18305.633887322445, 18561.25754969813, 319905.0, 327633.0, 328754.0, 392752.0, 414499.0, 59741.0, 60547.39452605417, 60828.391716083155, 60933.695331523944, 61100.0, 1271.910966232355, 1330.5143622577882, 1385.6051025457696, 1412.2867951684334, 1444.5377479206552, 31406.52890206603, 33606.49590256099, 34141.0, 36635.618684785404, 36688.44967325439, 56703.43296566982, 57241.42758572527, 57484.0, 57530.42469575418, 57561.0, 462340.75318494113, 465331.0, 466178.0, 466544.0, 467109.0, 27565.448691025667, 28231.71768282291, 30434.543481847348, 30804.38391232118, 48603.5139648599, 46523.76738116288, 50468.49531504785, 50521.74739126281, 51030.74484627553, 51102.0, 41778.79110604428, 42304.0, 42412.0, 43407.56592434035, 46827.765861170476, 42485.362719558616, 42987.570124298356, 43196.78401607972, 44653.77673111744, 45313.320300194864, 43920.78039609781, 44050.118997619225, 45338.0, 45363.31955020611, 45923.540764591926, 36185.27629447449, 38361.23277534489, 38448.423273651475, 39403.0, 39640.20719585534, 29202.561961571017, 29496.262593435335, 29498.705012950453, 29545.409091818474, 29877.253068673457], "value": 19959.187785792805, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.28799728492766, 413.59674317540384, 414.7428594271551, 418.2200196633279, 429.9914001719966, 14398.640033999156, 16118.274677639505, 19662.606747865033, 20179.495512612157, 20281.69577456339, 20228.08973596188, 20266.392008239738, 20450.59098818023, 20465.693014604793, 20881.58236835266, 5675.772969081232, 5718.656880587178, 5817.534597232232, 5863.648181109126, 5984.072468767326, 4316.352547117926, 4546.568076032788, 5768.04827203513, 5868.325142608612, 5868.853278668024, 6223.626582405071, 6488.448481879022, 6516.9674151629415, 6620.569662971916, 6620.966895165541, 6432.678366081701, 6603.108580341641, 6624.238212605515, 6629.966850165718, 6635.336466353374, 5705.686187259741, 5899.410058994066, 5903.586748927577, 5905.4980326672285, 5908.438698323653, 23505.882470587538, 23858.642120368208, 23906.0, 24472.265832025067, 24911.252662420156, 2476.925692229236, 2519.1057174703083, 2611.8824652890476, 2740.0135951057605, 2870.6842247352774, 14823.77764333536, 15373.615659608487, 15468.381264749396, 15468.458603948808, 15657.138857362841, 1934.564722937342, 1981.0391959899437, 1993.9601207975759, 1994.5312851480035, 1999.180336062222, 64340.67829660868, 79044.60477697632, 80290.19709802886, 84406.0, 88367.99999999936, 11292.943535282435, 11447.713807154887, 11648.650540483799, 11660.0, 11885.524579016743, 16025.0, 16036.358545658277, 16050.117243551716, 16052.598685032968, 16080.195990200444, 8580.184882436284, 8754.21212090915, 8780.604872780741, 8871.556422178865, 9008.59461324242, 8300.792480188044, 8326.45878017927, 8581.528015959182, 8614.22471977525, 8742.431741936754, 6968.44252459802, 7006.0, 7024.192217895007, 7049.753258635923, 7084.681189346492, 1970.862039657239, 2024.1093918675735, 2050.6718924971933, 2053.712480252766, 2054.897255137237, 943.7593413679468, 1010.3281317923645, 1012.9848052279222, 1099.587654629514, 1111.050052205369, 20296.797032029786, 20968.79031209699, 21343.67984480234, 21417.78582214158, 21482.57034859294, 1046.7435478307864, 1057.4025675493322, 1092.6612750047511, 1112.1769890281134, 1149.9827502587468, 11673.591424300108, 12008.939955300168, 12012.519499219934, 12058.638240852788, 12243.32661703615, 467129.0, 467433.0, 471988.0, 472086.0, 474334.0, 88983.55508222418, 89416.55291723499, 91157.54421227852, 91267.54366228127, 91528.0, 1005.788784355279, 1024.293237666008, 1028.8971102889652, 1043.3896170740202, 1057.3708643357345, 18175.909120454315, 18534.536636584195, 18557.721634175767, 18565.628687426444, 18842.281211446094, 41767.58232417759, 42065.57934420617, 42190.57809421867, 42457.787711061246, 43065.56934430741, 54369.72815135899, 54727.452725472234, 55506.44493555013, 55706.0, 65840.34159658341, 5670.092785154356, 7193.784186474414, 8182.018157820861, 8779.25376343018, 9473.289503287144, 12446.688832779253, 14193.503227386991, 14236.145831250158, 14252.28738563047, 14364.497242596459, 12052.276863388224, 12106.0, 12198.0, 12543.24740515572, 12659.113862029937, 12132.393380330772, 12245.387730613256, 12411.875881241072, 12521.373931303217, 12742.554010609585, 12254.877451225373, 12383.69040773988, 12419.565315214286, 12539.498420063095, 12561.63535823386, 8907.287417006495, 9057.501837398742, 9194.724158275263, 9383.718488445085, 9618.0, 9507.809843802946, 11660.591879284184, 11921.403929803648, 12417.503299867903, 12488.313142776871], "value": 9783.640401437151, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.4591390486844, 1482.5774654223544, 1505.7440235160022, 1630.9021458712477, 1784.5360206346338, 7697.2302769723, 7966.880496792553, 8365.832683346345, 8384.538850363235, 8572.314214862812, 8421.78945526362, 8485.490870547772, 8488.61801218945, 8491.91508084919, 8497.107803680601, 2468.6050231962936, 2500.599904015358, 2501.4246723253573, 2502.336880726609, 2507.636392723054, 2424.0788500369836, 2495.214007587604, 2500.1249562653106, 2505.448801263719, 2506.097804790274, 2405.7113146422366, 2420.4190994161363, 2441.060191826151, 2441.8046556275453, 2442.7312995570474, 2375.8336916415856, 2378.7859092681656, 2389.0443822470975, 2391.916282930089, 2411.9035238590436, 2384.940376490584, 2388.164142550111, 2400.8919598618104, 2402.6396040593877, 2421.3825474503888, 8444.57777111148, 8478.448900821488, 8766.035736069027, 8882.245009174228, 8883.20051195389, 5357.0, 5572.080606699889, 5575.944240557624, 5582.246396736429, 5582.720863956786, 3332.4001679697644, 3405.9829700851583, 3450.8102054387, 3458.8789392371145, 3479.669431404038, 1030.567161792049, 1063.136800544995, 1075.7902209069157, 1098.4233277529281, 1098.7967226063124, 12271.325077120755, 12320.445579948924, 12333.814992775115, 12378.62864114069, 12398.132130750852, 4263.59495847894, 4549.795259213277, 4590.311453282035, 4609.976950115295, 4636.095961287584, 9817.803643927082, 9832.262580306506, 9834.459104749307, 9834.75413114678, 9836.458994755214, 5286.920696189561, 5287.154055351125, 5326.813561525328, 5330.600204984642, 5330.760115794798, 4327.437433133736, 4401.493828209796, 4952.0, 5021.974890125599, 5037.496250374933, 3930.5872883346838, 3974.503187101612, 4037.9192416151513, 4079.2453396121814, 4079.347304431277, 1179.6932797472548, 1198.4367347346733, 1204.4158583087196, 1208.6615747590688, 1215.6778453709774, 989.9851502227473, 1009.7425156585024, 1033.7415646088477, 1035.9015893490107, 1038.7039693687263, 6174.783882564178, 6256.017800216603, 6323.241211054611, 6494.480441564663, 6531.281559028503, 458.26677316293666, 478.12741746313117, 479.6690283704233, 480.75962018990504, 497.6615901187155, 4452.414013566032, 4478.820847166142, 4487.394201782718, 4571.81712731487, 4602.332661764092, 11002.559897604166, 11219.495122719498, 11233.606823761129, 11235.438228088562, 11267.492962816694, 10381.584736610452, 10808.837867431996, 10988.890111098946, 11126.054285385833, 11133.0, 8328.167183281623, 8505.319574433906, 8526.232639062515, 8534.189252021048, 8535.061143274235, 6262.778758142119, 6274.278457977391, 6275.309715931153, 6382.489400847828, 6523.217213934358, 10245.282830201815, 10269.53787079598, 10291.22815788804, 10336.0697537222, 10362.274640775073, 10274.178065754571, 10522.053015228666, 10613.363198208132, 10626.628068017582, 10634.361938283728, 6996.0905082341005, 7222.96388518054, 7360.815979600553, 8077.1115177329275, 8203.466774659628, 8401.075881653136, 8740.737777866674, 8772.649094036167, 8784.297256219359, 8786.077461866413, 7766.961165194138, 7885.053793544811, 7909.406794490551, 7923.762287131395, 8079.636416361395, 7143.142822861082, 7237.927620723725, 7342.559446433231, 7417.592032438268, 7498.512596681198, 7082.5042247044275, 7154.034205382207, 7180.7845764627145, 7188.0, 7188.28117188277, 5415.08680902025, 5602.411746766694, 5602.963451761437, 5628.802991895428, 5703.800366987136, 5314.22943673173, 5448.645838020516, 5511.063119269814, 5512.3385193777, 5514.0], "value": 4631.193486347013, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.0461704917706, 340.16828853453285, 341.61227007346656, 343.72501998401276, 351.5798620648325, 5169.896602067956, 5212.872276634018, 5225.425203227642, 5273.839755253838, 5283.471652834714, 5203.817866374678, 5230.189320655304, 5233.73831308435, 5237.528622423991, 5245.501677340647, 4177.326511032941, 4187.225363307783, 4206.705530612859, 4210.936835947463, 4213.4522512073545, 3714.0, 3972.9205415891665, 4023.275810354135, 4033.495813023372, 4110.691698122638, 4061.614146656063, 4087.9182416351655, 4089.1821635672836, 4093.9385909211237, 4101.938470922939, 4098.8360465581345, 4106.733062350938, 4107.753534787892, 4109.198706252282, 4118.546959834416, 3524.559430071241, 3603.3333833240936, 3611.3860643690373, 3680.834362453696, 3682.926341473182, 5059.254557177768, 5084.771185296633, 5092.6180536459915, 5096.731954280347, 5098.898022039575, 4727.479977202505, 4736.360591320162, 4740.217864052462, 4747.596454301354, 4760.262159365304, 5191.871332753553, 5423.454315520058, 5454.318210223722, 5470.343558772933, 5472.288602481652, 4012.1574469361467, 4014.0165659413337, 4017.5336002358927, 4019.7588144711117, 4025.9597404025876, 5394.298741163623, 5483.369412517532, 5539.25220095286, 5660.235868157828, 5701.971490142523, 4065.4714887064492, 4068.4710987572016, 4072.6334629883454, 4100.958990410118, 4106.958930410718, 4100.7334523255895, 4128.669706423479, 4130.822715526121, 4135.648469880094, 4137.420761093449, 4552.385427967249, 4674.644353137552, 4709.788059537329, 4714.302850973647, 4722.6221902247735, 5417.972910135503, 5439.864003399867, 5452.40023597404, 5469.945300547023, 5495.972520137454, 1350.540816122523, 1365.938532766028, 1366.371469124214, 1366.760816857046, 1369.7465968795805, 917.6062114882095, 960.7454024683465, 966.5215718219526, 969.8399764038995, 973.8393165127816, 5124.666896651705, 5143.922841157386, 5144.768485418165, 5167.819126330561, 5213.530782229617, 5364.436734142859, 5386.875612144947, 5390.0, 5405.0, 5405.729713514309, 5054.671446355975, 5110.2590124431745, 5182.844514664566, 5195.428263009834, 5205.765740541685, 2847.601335812988, 2860.3278229616026, 2867.641544806899, 2868.756155726745, 2869.9282517937218, 5543.916841247385, 5556.805511807148, 5563.610547261734, 5604.3835178130375, 5616.522595579339, 5425.240466334716, 5464.207689884945, 5704.744956109649, 5712.914306285409, 5747.8563035924435, 5853.019946436464, 5882.705864706748, 5897.085951677411, 5901.0, 5909.409059094056, 5500.477454641803, 5605.607607467438, 5626.611243230287, 5642.802501912413, 5644.85887852807, 5532.5297349724115, 5550.171350924721, 5566.304211973504, 5568.415316391883, 5569.66582005081, 5600.299962504687, 5708.65748055118, 5720.427957204246, 5869.618474799125, 5905.940940590711, 4115.670746340225, 4135.937960930528, 4136.751794892316, 4140.399642051946, 4144.647704945113, 5447.346318441812, 5556.0, 5649.71751412436, 5651.773929042956, 5653.830385088454, 5378.542823860016, 5408.432114627907, 5482.725863706878, 5493.818828951795, 5497.780088796403, 5433.782648694008, 5454.427285135003, 5478.863028424321, 5489.58828087887, 5503.972480137573, 5220.817271395483, 5383.1117865553315, 5384.865378365572, 5416.0, 5430.212619170119, 5442.972785136049, 5457.481539253765, 5464.508194262536, 5466.234727138124, 5473.72631368422, 5354.0, 5363.839084827461, 5384.542313903362, 5385.865353366197, 5394.487523685244], "value": 4179.770146727321, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.5420656019505, 803.036356372353, 819.5082950229862, 842.3489959679029, 884.0408157149493, 15124.697506049872, 16113.597160071004, 16704.248308826103, 16866.0, 16948.745768813445, 15266.0, 15351.769723454157, 15702.921485392557, 16278.75581866273, 16434.671306573862, 729.6534146280527, 858.6093327535956, 877.0703054761941, 877.745453818395, 879.9780005499849, 726.1757904778088, 735.0774777654036, 735.5549892315153, 815.6003558256441, 861.8362511122898, 911.9270458363314, 918.4351623751378, 918.4489306416131, 919.1130559010553, 919.462114662923, 905.7690288976302, 907.2515174980667, 907.773056735816, 908.3232991421401, 908.6047569307307, 552.7429745168516, 576.2652617912154, 621.6239175298924, 672.865426914619, 673.2123415603755, 15019.399224031025, 15240.314185861664, 15740.685186296325, 15745.370185192578, 15761.448349307833, 13660.863391366158, 13862.792058119136, 14057.64855878601, 14184.574462766133, 14318.069325493914, 10886.346819190874, 13004.499982519423, 13208.933955330258, 13485.932570337183, 13533.458661653522, 3940.7635541867294, 4506.256467682829, 4545.0909818036525, 4605.861824145281, 4691.953080469186, 60367.0, 69043.65478172628, 69060.61878762397, 71194.0, 71409.64295178543, 11987.940060299643, 12062.215955963009, 12114.333711645768, 12157.088218383657, 12572.0, 20206.191752330036, 20234.595308093754, 21672.13311467555, 21975.0, 22299.55400891973, 7901.762947111596, 8169.101398846122, 8607.784805379915, 8646.52444115567, 8827.301260971839, 4804.495527969513, 4846.5638092571135, 4904.754762261872, 4965.677230980047, 4967.180415231519, 3510.280392519527, 3592.2276710507367, 3593.3711600470338, 3631.382664946965, 3637.5816781070516, 629.8464006655046, 641.1824923222835, 657.086649557116, 657.3853447027045, 664.9700763465559, 602.9125776762345, 679.6805501414327, 738.8596166728412, 775.7827808213709, 787.3661702329565, 9563.474008929437, 10031.846337671115, 10245.641402550875, 10704.571817127227, 10707.518161682743, 661.867626474707, 683.3781259054336, 733.9119305683353, 777.7472321495536, 792.2513225002317, 4563.406757121553, 4795.859975617589, 4852.539008794159, 4878.341423907798, 4930.408350997904, 84929.57535212285, 88447.0, 89537.0, 90086.09913900908, 94505.5274723622, 67175.0, 68635.65682171557, 74421.25578744251, 75011.24988750152, 79431.60284198543, 68268.6586567074, 69088.6545567279, 70653.0, 71091.93362099573, 71416.285837142, 10020.799584008424, 10063.49682515886, 10159.339642923187, 10431.453305876039, 10656.360618362924, 34818.129546761535, 34968.82515587406, 45028.588549598215, 46909.29636055393, 49069.0, 63156.368436315046, 64591.0, 65414.67292663506, 66555.33444665685, 66986.6650666763, 12370.381480926098, 12844.743105138032, 12870.935645321713, 13092.80360794608, 13439.529616463376, 14235.50175743844, 14342.641433964234, 14815.777763333774, 15204.923975380052, 16056.518304450885, 13326.93336533311, 13639.590812275648, 13672.248026358646, 13830.93084534611, 13979.301034948416, 13407.664808379868, 13506.392212350664, 13514.729705406033, 13823.723525529233, 13984.44062237499, 13030.869691302965, 13051.804222936853, 13264.867351326364, 13383.933080334536, 13606.523771667944, 11653.417329133339, 11730.88269117298, 11859.644210673694, 11925.105617078927, 12209.572664956684, 11765.470553824927, 11942.34317112567, 11960.162788604714, 12046.65012406948, 12178.878211218129], "value": 7520.2090101452195, "unit": "score"}], "name": "dromaeo_css", "value": 7306.943375285343, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 111.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 118.0, 152.0, 118.0, 117.0, 118.0, 117.0, 148.0, 118.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 161.0, 159.0, 163.0, 160.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 115.0, 76.0, 76.0, 76.0, 77.0, 97.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 119.0, 81.0, 81.0, 81.0, 98.0, 81.0, 81.0, 105.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 116.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 50.0, 51.0, 51.0, 52.0, 51.0, 50.0, 50.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 62.0, 59.0, 59.0, 62.0, 59.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 75.0, 75.0, 75.0, 76.0, 76.0, 74.0, 82.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 139.0, 126.0, 132.0, 119.0, 130.0, 119.0, 119.0, 173.0, 140.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 162.0, 161.0, 158.0, 160.0, 157.0, 158.0, 160.0, 155.0], "value": 158.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0, 61.0, 63.0, 61.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1457.0, "alertThreshold": 2.0}]} 09:50:13 INFO - Return code: 0 09:50:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:50:13 INFO - # TBPL SUCCESS # 09:50:13 INFO - Running post-action listener: _resource_record_post_action 09:50:13 INFO - [mozharness: 2017-01-03 17:50:13.736295Z] Finished run-tests step (success) 09:50:13 INFO - Running post-run listener: _resource_record_post_run 09:50:13 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 716800; Write bytes: 366395392; Read time: 92; Write time: 238260 09:50:13 INFO - TinderboxPrint: CPU usage
13.0% 09:50:13 INFO - TinderboxPrint: I/O read bytes / time
716,800 / 92 09:50:13 INFO - TinderboxPrint: I/O write bytes / time
366,395,392 / 238,260 09:50:13 INFO - TinderboxPrint: CPU idle
8,280.5 (87.0%) 09:50:13 INFO - TinderboxPrint: CPU user
1,188.0 (12.5%) 09:50:13 INFO - TinderboxPrint: Swap in / out
0 / 0 09:50:13 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118689792; Read time: 0; Write time: 84596 09:50:13 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 712704; Write bytes: 247226368; Read time: 88; Write time: 153660 09:50:14 INFO - Running post-run listener: _upload_blobber_files 09:50:14 INFO - Blob upload gear active. 09:50:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:14 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 09:50:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:50:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:50:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:50:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:15 INFO - (blobuploader) - INFO - Done attempting. 09:50:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:50:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:19 INFO - (blobuploader) - INFO - Done attempting. 09:50:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:50:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:50:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:20 INFO - (blobuploader) - INFO - Done attempting. 09:50:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:20 INFO - Return code: 0 09:50:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b8124f67a6869d15597bd0398a17b5f175a054bb5a3f3dac90abf84a8f65ede4307f0b0fccd740f53c00e53d4b871a162d5baa15a91109ddfe54248e2382264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f326862ed51deb5e2a7fd47cde2679f5433b7d6889fe1b8e92c024639b3370d9a1b826a19eaf140b3c31f1dcd58e236f233bc2a1e6960807b10c1000968c4b59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7b925e8df2045cea82dbd7ac830af6a8cfb142344225663a8b4432b820e23511a1732aeefcd0e0fc943a0b3b442ac834e258893a41a3d92f35194686157584a"} 09:50:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:20 INFO - Contents: 09:50:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b8124f67a6869d15597bd0398a17b5f175a054bb5a3f3dac90abf84a8f65ede4307f0b0fccd740f53c00e53d4b871a162d5baa15a91109ddfe54248e2382264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f326862ed51deb5e2a7fd47cde2679f5433b7d6889fe1b8e92c024639b3370d9a1b826a19eaf140b3c31f1dcd58e236f233bc2a1e6960807b10c1000968c4b59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7b925e8df2045cea82dbd7ac830af6a8cfb142344225663a8b4432b820e23511a1732aeefcd0e0fc943a0b3b442ac834e258893a41a3d92f35194686157584a"} 09:50:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:20 INFO - Copying logs to upload dir... 09:50:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.067658 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-01-03 09:50:20.017353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 09:50:20.024524) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b8124f67a6869d15597bd0398a17b5f175a054bb5a3f3dac90abf84a8f65ede4307f0b0fccd740f53c00e53d4b871a162d5baa15a91109ddfe54248e2382264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f326862ed51deb5e2a7fd47cde2679f5433b7d6889fe1b8e92c024639b3370d9a1b826a19eaf140b3c31f1dcd58e236f233bc2a1e6960807b10c1000968c4b59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7b925e8df2045cea82dbd7ac830af6a8cfb142344225663a8b4432b820e23511a1732aeefcd0e0fc943a0b3b442ac834e258893a41a3d92f35194686157584a"} build_url:https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011717 build_url: 'https://queue.taskcluster.net/v1/task/XV0KPjWUQFyYG9DvUaXkRg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b8124f67a6869d15597bd0398a17b5f175a054bb5a3f3dac90abf84a8f65ede4307f0b0fccd740f53c00e53d4b871a162d5baa15a91109ddfe54248e2382264", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f326862ed51deb5e2a7fd47cde2679f5433b7d6889fe1b8e92c024639b3370d9a1b826a19eaf140b3c31f1dcd58e236f233bc2a1e6960807b10c1000968c4b59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7b925e8df2045cea82dbd7ac830af6a8cfb142344225663a8b4432b820e23511a1732aeefcd0e0fc943a0b3b442ac834e258893a41a3d92f35194686157584a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 09:50:20.052968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:50:20.053382) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1483464009.751702-746810296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 09:50:20.134804) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 09:50:20.137986) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 09:50:20.138405) ========= ========= Total master_lag: 0.16 =========