builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1477287044.2 results: success (0) buildid: 20161023193001 builduid: 3509c31fc4c44e78bfecb791df3309a1 revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.196760) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.197184) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.197477) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.213397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.213709) ========= 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=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-23 22:30:44-- 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% 46.5M=0s 2016-10-23 22:30:44 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084186 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.311216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.311534) ========= 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=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020312 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.360871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:44.361197) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 215f9686117673a2c914ed207bc7da9bb8d741ad --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 215f9686117673a2c914ed207bc7da9bb8d741ad --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=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-23 22:30:45,061 truncating revision to first 12 chars 2016-10-23 22:30:45,061 Setting DEBUG logging. 2016-10-23 22:30:45,061 attempt 1/10 2016-10-23 22:30:45,061 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/215f96861176?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-23 22:30:45,508 unpacking tar archive at: mozilla-central-215f96861176/testing/mozharness/ program finished with exit code 0 elapsedTime=0.797024 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:45.171323) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:45.171607) ========= script_repo_revision: 215f9686117673a2c914ed207bc7da9bb8d741ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:45.171992) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:45.172258) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-23 22:30:45.181758) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-10-23 22:30:45.182045) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:30:45 INFO - MultiFileLogger online at 20161023 22:30:45 in /builds/slave/test 22:30:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:30:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:30:45 INFO - {'append_to_log': False, 22:30:45 INFO - 'base_work_dir': '/builds/slave/test', 22:30:45 INFO - 'blob_upload_branch': 'Firefox', 22:30:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:30:45 INFO - 'branch': 'Firefox', 22:30:45 INFO - 'buildbot_json_path': 'buildprops.json', 22:30:45 INFO - 'config_files': ('talos/linux_config.py',), 22:30:45 INFO - 'default_actions': ('clobber', 22:30:45 INFO - 'read-buildbot-config', 22:30:45 INFO - 'download-and-extract', 22:30:45 INFO - 'populate-webroot', 22:30:45 INFO - 'create-virtualenv', 22:30:45 INFO - 'install', 22:30:45 INFO - 'run-tests'), 22:30:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:30:45 INFO - 'download_minidump_stackwalk': True, 22:30:45 INFO - 'download_symbols': 'ondemand', 22:30:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:30:45 INFO - 'tooltool.py': '/tools/tooltool.py', 22:30:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:30:45 INFO - '/tools/misc-python/virtualenv.py')}, 22:30:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:30:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:30:45 INFO - 'installer_path': 'installer.exe', 22:30:45 INFO - 'log_level': 'info', 22:30:45 INFO - 'log_name': 'talos', 22:30:45 INFO - 'log_to_console': True, 22:30:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:30:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:30:45 INFO - 'opt_config_files': (), 22:30:45 INFO - 'pip_index': False, 22:30:45 INFO - 'sps_profile': False, 22:30:45 INFO - 'sps_profile_interval': 0, 22:30:45 INFO - 'suite': 'dromaeojs', 22:30:45 INFO - 'system_bits': '32', 22:30:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:30:45 INFO - 'title': 'talos-linux64-ix-001', 22:30:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:30:45 INFO - 'use_talos_json': True, 22:30:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:30:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:30:45 INFO - 'work_dir': 'build'} 22:30:45 INFO - [mozharness: 2016-10-24 05:30:45.979303Z] Running clobber step. 22:30:45 INFO - Running pre-action listener: _resource_record_pre_action 22:30:45 INFO - Running main action method: clobber 22:30:45 INFO - rmtree: /builds/slave/test/build 22:30:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:30:46 INFO - Running post-action listener: _resource_record_post_action 22:30:46 INFO - [mozharness: 2016-10-24 05:30:46.436906Z] Finished clobber step (success) 22:30:46 INFO - [mozharness: 2016-10-24 05:30:46.437007Z] Running read-buildbot-config step. 22:30:46 INFO - Running pre-action listener: _resource_record_pre_action 22:30:46 INFO - Running main action method: read_buildbot_config 22:30:46 INFO - Using buildbot properties: 22:30:46 INFO - { 22:30:46 INFO - "project": "", 22:30:46 INFO - "product": "firefox", 22:30:46 INFO - "script_repo_revision": "production", 22:30:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:30:46 INFO - "repository": "", 22:30:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 22:30:46 INFO - "buildid": "20161023193001", 22:30:46 INFO - "pgo_build": "True", 22:30:46 INFO - "basedir": "/builds/slave/test", 22:30:46 INFO - "buildnumber": 27, 22:30:46 INFO - "slavename": "talos-linux64-ix-001", 22:30:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:30:46 INFO - "platform": "ubuntu64_hw", 22:30:46 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:30:46 INFO - "branch": "mozilla-central", 22:30:46 INFO - "repo_path": "mozilla-central", 22:30:46 INFO - "revision": "215f9686117673a2c914ed207bc7da9bb8d741ad", 22:30:46 INFO - "stage_platform": "linux64-pgo", 22:30:46 INFO - "builduid": "3509c31fc4c44e78bfecb791df3309a1", 22:30:46 INFO - "slavebuilddir": "test" 22:30:46 INFO - } 22:30:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 22:30:46 INFO - Running post-action listener: _resource_record_post_action 22:30:46 INFO - [mozharness: 2016-10-24 05:30:46.439485Z] Finished read-buildbot-config step (success) 22:30:46 INFO - [mozharness: 2016-10-24 05:30:46.439575Z] Running download-and-extract step. 22:30:46 INFO - Running pre-action listener: _resource_record_pre_action 22:30:46 INFO - Running main action method: download_and_extract 22:30:46 INFO - mkdir: /builds/slave/test/build/tests 22:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:30:46 INFO - trying https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:30:47 INFO - Downloaded 1361 bytes. 22:30:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 22:30:47 INFO - Using the following test package requirements: 22:30:47 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:47 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:30:47 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:30:47 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'jsshell-linux-x86_64.zip'], 22:30:47 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:30:47 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 22:30:47 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:30:47 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:30:47 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:30:47 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 22:30:47 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:30:47 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:30:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:30:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:47 INFO - Fetch https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:30:47 INFO - Http code: 200 22:30:47 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 22:30:47 INFO - x-amz-cf-id: 723uG8KM2OScJJetWAdIDy8SuwIK1O-RLLi7fi1FUNKv2Y-5D00sGA== 22:30:47 INFO - x-amz-version-id: y.DDuLscrR14yyvhPJY7Zo_q6lIAQ8AO 22:30:48 INFO - Expected file size: 22022064 22:30:48 INFO - Obtained file size: 22022064 22:30:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 22:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:30:53 INFO - Http code: 200 22:30:53 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 22:30:53 INFO - x-amz-cf-id: m8qZggNOSKn6E8roNhRF7GbnCp41c8HnyILPso0QQ9EHWOeSYTMoug== 22:30:53 INFO - x-amz-version-id: y.DDuLscrR14yyvhPJY7Zo_q6lIAQ8AO 22:30:53 INFO - Expected file size: 22022064 22:30:53 INFO - Obtained file size: 22022064 22:30:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 22:30:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:30:58 INFO - Fetch https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:30:58 INFO - Http code: 200 22:30:58 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 22:30:58 INFO - x-amz-cf-id: BU7xnaeg0DyFh9J9H6vU7tUG0ZetVCaAE33mpTqcIYqZyyzDG1iJpQ== 22:30:58 INFO - x-amz-version-id: m0I9ldyZ4t8CtiEmo.w37mczHnFUlGMj 22:31:00 INFO - Expected file size: 14409028 22:31:00 INFO - Obtained file size: 14409028 22:31: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')]} 22:31:00 INFO - trying https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:31:00 INFO - Downloading https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:31:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:31:02 INFO - Downloaded 59367226 bytes. 22:31:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:31:02 INFO - mkdir: /builds/slave/test/properties 22:31:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:31:02 INFO - Writing to file /builds/slave/test/properties/build_url 22:31:02 INFO - Contents: 22:31:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 22:31:03 INFO - Running post-action listener: _resource_record_post_action 22:31:03 INFO - Running post-action listener: set_extra_try_arguments 22:31:03 INFO - [mozharness: 2016-10-24 05:31:03.412118Z] Finished download-and-extract step (success) 22:31:03 INFO - [mozharness: 2016-10-24 05:31:03.412348Z] Running populate-webroot step. 22:31:03 INFO - Running pre-action listener: _resource_record_pre_action 22:31:03 INFO - Running main action method: populate_webroot 22:31:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:31:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:31:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:31:03 INFO - '--tppagecycles', 22:31:03 INFO - '7'], 22:31:03 INFO - 'tests': ['tsvgm']}}, 22:31:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:31:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:31:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:31:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:31:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:31:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['damp', 'tps']}, 22:31:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'tests': ['damp', 'tps']}, 22:31:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['dromaeo_dom']}, 22:31:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:31:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['basic_compositor_video']}, 22:31:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:31:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'other-e10s': {'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['a11yr', 22:31:03 INFO - 'ts_paint', 22:31:03 INFO - 'tpaint', 22:31:03 INFO - 'sessionrestore', 22:31:03 INFO - 'sessionrestore_no_auto_restore', 22:31:03 INFO - 'tabpaint']}, 22:31:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['tsvgx', 22:31:03 INFO - 'tsvgr_opacity', 22:31:03 INFO - 'tart', 22:31:03 INFO - 'tscrollx', 22:31:03 INFO - 'cart']}, 22:31:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:31:03 INFO - 'tsvgr_opacity', 22:31:03 INFO - 'tart', 22:31:03 INFO - 'tscrollx', 22:31:03 INFO - 'cart']}, 22:31:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'talos_options': ['--disable-e10s'], 22:31:03 INFO - 'tests': ['tp5o']}, 22:31:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'tests': ['tp5o']}, 22:31:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'talos_options': ['--disable-e10s', 22:31:03 INFO - '--xperf_path', 22:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:03 INFO - 'tests': ['tp5n']}, 22:31:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:31:03 INFO - 'talos_options': ['--xperf_path', 22:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:31:03 INFO - 'tests': ['tp5n']}}, 22:31:03 INFO - 'talos.zip': {'path': '', 22:31:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:31:03 INFO - Running post-action listener: _resource_record_post_action 22:31:03 INFO - [mozharness: 2016-10-24 05:31:03.433056Z] Finished populate-webroot step (success) 22:31:03 INFO - [mozharness: 2016-10-24 05:31:03.433145Z] Running create-virtualenv step. 22:31:03 INFO - Running pre-action listener: _resource_record_pre_action 22:31:03 INFO - Running main action method: create_virtualenv 22:31:03 INFO - Creating virtualenv /builds/slave/test/build/venv 22:31:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:31:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:31:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:31:03 INFO - Using real prefix '/usr' 22:31:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:31:04 INFO - Installing distribute.............................................................................................................................................................................................done. 22:31:06 INFO - Installing pip.................done. 22:31:06 INFO - Return code: 0 22:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:31:06 INFO - Reading from file tmpfile_stdout 22:31:06 INFO - Output received: 22:31:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:31:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:31: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')]} 22:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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')]} 22:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31: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', 'pip>=1.5'] in /builds/slave/test/build 22:31: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 pip>=1.5 22:31:06 INFO - Using env: {'DISPLAY': ':0', 22:31:06 INFO - 'HOME': '/home/cltbld', 22:31:06 INFO - 'LANG': 'en_US.UTF-8', 22:31:06 INFO - 'LANGUAGE': 'en_US:en', 22:31:06 INFO - 'LOGNAME': 'cltbld', 22:31:06 INFO - 'MAIL': '/var/mail/cltbld', 22:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:06 INFO - 'MOZ_NO_REMOTE': '1', 22:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:06 INFO - 'NO_EM_RESTART': '1', 22:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:06 INFO - 'PWD': '/builds/slave/test', 22:31:06 INFO - 'SHELL': '/bin/bash', 22:31:06 INFO - 'SHLVL': '1', 22:31:06 INFO - 'TERM': 'linux', 22:31:06 INFO - 'TMOUT': '86400', 22:31:06 INFO - 'USER': 'cltbld', 22:31:06 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:06 INFO - '_': '/tools/buildbot/bin/python'} 22:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:31:06 INFO - Cleaning up... 22:31:06 INFO - Return code: 0 22:31:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:31: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')]} 22:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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')]} 22:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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', '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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:31: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 psutil>=3.1.1 22:31:06 INFO - Using env: {'DISPLAY': ':0', 22:31:06 INFO - 'HOME': '/home/cltbld', 22:31:06 INFO - 'LANG': 'en_US.UTF-8', 22:31:06 INFO - 'LANGUAGE': 'en_US:en', 22:31:06 INFO - 'LOGNAME': 'cltbld', 22:31:06 INFO - 'MAIL': '/var/mail/cltbld', 22:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:06 INFO - 'MOZ_NO_REMOTE': '1', 22:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:06 INFO - 'NO_EM_RESTART': '1', 22:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:06 INFO - 'PWD': '/builds/slave/test', 22:31:06 INFO - 'SHELL': '/bin/bash', 22:31:06 INFO - 'SHLVL': '1', 22:31:06 INFO - 'TERM': 'linux', 22:31:06 INFO - 'TMOUT': '86400', 22:31:06 INFO - 'USER': 'cltbld', 22:31:06 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:06 INFO - '_': '/tools/buildbot/bin/python'} 22:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:06 INFO - Downloading/unpacking psutil>=3.1.1 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:31:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:08 INFO - Installing collected packages: psutil 22:31:08 INFO - Running setup.py install for psutil 22:31:08 INFO - building 'psutil._psutil_linux' extension 22:31:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:31:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:31:08 INFO - building 'psutil._psutil_posix' extension 22:31:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:31:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:31:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:31:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:31:09 INFO - Successfully installed psutil 22:31:09 INFO - Cleaning up... 22:31:09 INFO - Return code: 0 22:31:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:31: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')]} 22:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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')]} 22:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:31: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 mozsystemmonitor==0.3 22:31:09 INFO - Using env: {'DISPLAY': ':0', 22:31:09 INFO - 'HOME': '/home/cltbld', 22:31:09 INFO - 'LANG': 'en_US.UTF-8', 22:31:09 INFO - 'LANGUAGE': 'en_US:en', 22:31:09 INFO - 'LOGNAME': 'cltbld', 22:31:09 INFO - 'MAIL': '/var/mail/cltbld', 22:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:09 INFO - 'MOZ_NO_REMOTE': '1', 22:31:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:09 INFO - 'NO_EM_RESTART': '1', 22:31:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:09 INFO - 'PWD': '/builds/slave/test', 22:31:09 INFO - 'SHELL': '/bin/bash', 22:31:09 INFO - 'SHLVL': '1', 22:31:09 INFO - 'TERM': 'linux', 22:31:09 INFO - 'TMOUT': '86400', 22:31:09 INFO - 'USER': 'cltbld', 22:31:09 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:09 INFO - '_': '/tools/buildbot/bin/python'} 22:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:31:11 INFO - Installing collected packages: mozsystemmonitor 22:31:11 INFO - Running setup.py install for mozsystemmonitor 22:31:11 INFO - Successfully installed mozsystemmonitor 22:31:11 INFO - Cleaning up... 22:31:11 INFO - Return code: 0 22:31:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:11 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:31:11 INFO - Using env: {'DISPLAY': ':0', 22:31:11 INFO - 'HOME': '/home/cltbld', 22:31:11 INFO - 'LANG': 'en_US.UTF-8', 22:31:11 INFO - 'LANGUAGE': 'en_US:en', 22:31:11 INFO - 'LOGNAME': 'cltbld', 22:31:11 INFO - 'MAIL': '/var/mail/cltbld', 22:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:11 INFO - 'MOZ_NO_REMOTE': '1', 22:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:11 INFO - 'NO_EM_RESTART': '1', 22:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:11 INFO - 'PWD': '/builds/slave/test', 22:31:11 INFO - 'SHELL': '/bin/bash', 22:31:11 INFO - 'SHLVL': '1', 22:31:11 INFO - 'TERM': 'linux', 22:31:11 INFO - 'TMOUT': '86400', 22:31:11 INFO - 'USER': 'cltbld', 22:31:11 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:11 INFO - '_': '/tools/buildbot/bin/python'} 22:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:11 INFO - Downloading/unpacking jsonschema==2.5.1 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31: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 22:31:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:31:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:13 INFO - Downloading functools32-3.2.3-2.tar.gz 22:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:31:13 INFO - warning: no files found matching '*.txt' 22:31:13 INFO - no previously-included directories found matching 'build' 22:31:13 INFO - no previously-included directories found matching 'dist' 22:31:13 INFO - no previously-included directories found matching '.git*' 22:31:14 INFO - Installing collected packages: jsonschema, functools32 22:31:14 INFO - Running setup.py install for functools32 22:31:14 INFO - warning: no files found matching '*.txt' 22:31:14 INFO - no previously-included directories found matching 'build' 22:31:14 INFO - no previously-included directories found matching 'dist' 22:31:14 INFO - no previously-included directories found matching '.git*' 22:31:14 INFO - Successfully installed jsonschema functools32 22:31:14 INFO - Cleaning up... 22:31:14 INFO - Return code: 0 22:31:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:31:14 INFO - Using env: {'DISPLAY': ':0', 22:31:14 INFO - 'HOME': '/home/cltbld', 22:31:14 INFO - 'LANG': 'en_US.UTF-8', 22:31:14 INFO - 'LANGUAGE': 'en_US:en', 22:31:14 INFO - 'LOGNAME': 'cltbld', 22:31:14 INFO - 'MAIL': '/var/mail/cltbld', 22:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:14 INFO - 'MOZ_NO_REMOTE': '1', 22:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:14 INFO - 'NO_EM_RESTART': '1', 22:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:14 INFO - 'PWD': '/builds/slave/test', 22:31:14 INFO - 'SHELL': '/bin/bash', 22:31:14 INFO - 'SHLVL': '1', 22:31:14 INFO - 'TERM': 'linux', 22:31:14 INFO - 'TMOUT': '86400', 22:31:14 INFO - 'USER': 'cltbld', 22:31:14 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:14 INFO - '_': '/tools/buildbot/bin/python'} 22:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:31:14 INFO - Cleaning up... 22:31:14 INFO - Return code: 0 22:31:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:14 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:31:14 INFO - Using env: {'DISPLAY': ':0', 22:31:14 INFO - 'HOME': '/home/cltbld', 22:31:14 INFO - 'LANG': 'en_US.UTF-8', 22:31:14 INFO - 'LANGUAGE': 'en_US:en', 22:31:14 INFO - 'LOGNAME': 'cltbld', 22:31:14 INFO - 'MAIL': '/var/mail/cltbld', 22:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:14 INFO - 'MOZ_NO_REMOTE': '1', 22:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:14 INFO - 'NO_EM_RESTART': '1', 22:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:14 INFO - 'PWD': '/builds/slave/test', 22:31:14 INFO - 'SHELL': '/bin/bash', 22:31:14 INFO - 'SHLVL': '1', 22:31:14 INFO - 'TERM': 'linux', 22:31:14 INFO - 'TMOUT': '86400', 22:31:14 INFO - 'USER': 'cltbld', 22:31:14 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:14 INFO - '_': '/tools/buildbot/bin/python'} 22:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:14 INFO - Downloading/unpacking blobuploader==1.2.4 22:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:31:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:31:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:17 INFO - Downloading docopt-0.6.1.tar.gz 22:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:31:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:31:17 INFO - Running setup.py install for blobuploader 22:31:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:31:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:31:17 INFO - Running setup.py install for requests 22:31:17 INFO - Running setup.py install for docopt 22:31:17 INFO - Successfully installed blobuploader requests docopt 22:31:17 INFO - Cleaning up... 22:31:17 INFO - Return code: 0 22:31:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:17 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:31:17 INFO - Using env: {'DISPLAY': ':0', 22:31:17 INFO - 'HOME': '/home/cltbld', 22:31:17 INFO - 'LANG': 'en_US.UTF-8', 22:31:17 INFO - 'LANGUAGE': 'en_US:en', 22:31:17 INFO - 'LOGNAME': 'cltbld', 22:31:17 INFO - 'MAIL': '/var/mail/cltbld', 22:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:17 INFO - 'MOZ_NO_REMOTE': '1', 22:31:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:17 INFO - 'NO_EM_RESTART': '1', 22:31:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:17 INFO - 'PWD': '/builds/slave/test', 22:31:17 INFO - 'SHELL': '/bin/bash', 22:31:17 INFO - 'SHLVL': '1', 22:31:17 INFO - 'TERM': 'linux', 22:31:17 INFO - 'TMOUT': '86400', 22:31:17 INFO - 'USER': 'cltbld', 22:31:17 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:17 INFO - '_': '/tools/buildbot/bin/python'} 22:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:18 INFO - Running setup.py (path:/tmp/pip-5hPJ58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:18 INFO - Running setup.py (path:/tmp/pip-prjxEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:18 INFO - Running setup.py (path:/tmp/pip-FwI1Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:18 INFO - Running setup.py (path:/tmp/pip-AHupmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:18 INFO - Running setup.py (path:/tmp/pip-ofo8_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:18 INFO - Running setup.py (path:/tmp/pip-_CueE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:18 INFO - Running setup.py (path:/tmp/pip-mOnGPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:18 INFO - Running setup.py (path:/tmp/pip-bFWJtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:18 INFO - Running setup.py (path:/tmp/pip-nYEvYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:18 INFO - Running setup.py (path:/tmp/pip-C5FMxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:18 INFO - Running setup.py (path:/tmp/pip-YDVtbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:18 INFO - Running setup.py (path:/tmp/pip-49pVNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:19 INFO - Running setup.py (path:/tmp/pip-LFx1zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:19 INFO - Running setup.py (path:/tmp/pip-lg9efG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:19 INFO - Running setup.py (path:/tmp/pip-2lcv2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:19 INFO - Running setup.py (path:/tmp/pip-jgGvlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:19 INFO - Running setup.py (path:/tmp/pip-QV3nwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:31:19 INFO - Running setup.py install for manifestparser 22:31:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:31:19 INFO - Running setup.py install for mozcrash 22:31:19 INFO - Running setup.py install for mozdebug 22:31:19 INFO - Running setup.py install for mozdevice 22:31:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:31:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:31:19 INFO - Running setup.py install for mozfile 22:31:19 INFO - Running setup.py install for mozhttpd 22:31:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozinfo 22:31:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozInstall 22:31:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozleak 22:31:20 INFO - Running setup.py install for mozlog 22:31:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for moznetwork 22:31:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozprocess 22:31:20 INFO - Running setup.py install for mozprofile 22:31:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozrunner 22:31:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:31:20 INFO - Running setup.py install for mozscreenshot 22:31:21 INFO - Running setup.py install for moztest 22:31:21 INFO - Running setup.py install for mozversion 22:31:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:31:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:31:21 INFO - Cleaning up... 22:31:21 INFO - Return code: 0 22:31:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:21 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:31:21 INFO - Using env: {'DISPLAY': ':0', 22:31:21 INFO - 'HOME': '/home/cltbld', 22:31:21 INFO - 'LANG': 'en_US.UTF-8', 22:31:21 INFO - 'LANGUAGE': 'en_US:en', 22:31:21 INFO - 'LOGNAME': 'cltbld', 22:31:21 INFO - 'MAIL': '/var/mail/cltbld', 22:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:21 INFO - 'MOZ_NO_REMOTE': '1', 22:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:21 INFO - 'NO_EM_RESTART': '1', 22:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:21 INFO - 'PWD': '/builds/slave/test', 22:31:21 INFO - 'SHELL': '/bin/bash', 22:31:21 INFO - 'SHLVL': '1', 22:31:21 INFO - 'TERM': 'linux', 22:31:21 INFO - 'TMOUT': '86400', 22:31:21 INFO - 'USER': 'cltbld', 22:31:21 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:21 INFO - '_': '/tools/buildbot/bin/python'} 22:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:31:21 INFO - Running setup.py (path:/tmp/pip-vhsGAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:31:21 INFO - Running setup.py (path:/tmp/pip-wGfm7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:31:21 INFO - Running setup.py (path:/tmp/pip-FsCTc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:31:21 INFO - Running setup.py (path:/tmp/pip-llpCRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:31:21 INFO - Running setup.py (path:/tmp/pip-BtdvYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:31:21 INFO - Running setup.py (path:/tmp/pip-QBuVlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:31:21 INFO - Running setup.py (path:/tmp/pip-CIs_3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:31:21 INFO - Running setup.py (path:/tmp/pip-LDhAty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:31:22 INFO - Running setup.py (path:/tmp/pip-2t8LC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:31:22 INFO - Running setup.py (path:/tmp/pip-80k1vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:31:22 INFO - Running setup.py (path:/tmp/pip-mgUmDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:31:22 INFO - Running setup.py (path:/tmp/pip-dlKvPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:31:22 INFO - Running setup.py (path:/tmp/pip-pSkwUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:31:22 INFO - Running setup.py (path:/tmp/pip-KZfTdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:22 INFO - Running setup.py (path:/tmp/pip-9HwpIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:31:22 INFO - Running setup.py (path:/tmp/pip-OCNspR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:31:22 INFO - Running setup.py (path:/tmp/pip-lOaV1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:31:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:31:24 INFO - Downloading blessings-1.6.tar.gz 22:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:31:24 INFO - Installing collected packages: blessings 22:31:24 INFO - Running setup.py install for blessings 22:31:24 INFO - Successfully installed blessings 22:31:24 INFO - Cleaning up... 22:31:24 INFO - Return code: 0 22:31:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:31:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:25 INFO - Reading from file tmpfile_stdout 22:31:25 INFO - Current package versions: 22:31:25 INFO - argparse == 1.2.1 22:31:25 INFO - blessings == 1.6 22:31:25 INFO - blobuploader == 1.2.4 22:31:25 INFO - docopt == 0.6.1 22:31:25 INFO - functools32 == 3.2.3-2 22:31:25 INFO - jsonschema == 2.5.1 22:31:25 INFO - manifestparser == 1.1 22:31:25 INFO - mozInstall == 1.12 22:31:25 INFO - mozcrash == 0.17 22:31:25 INFO - mozdebug == 0.1 22:31:25 INFO - mozdevice == 0.48 22:31:25 INFO - mozfile == 1.2 22:31:25 INFO - mozhttpd == 0.7 22:31:25 INFO - mozinfo == 0.9 22:31:25 INFO - mozleak == 0.1 22:31:25 INFO - mozlog == 3.4 22:31:25 INFO - moznetwork == 0.27 22:31:25 INFO - mozprocess == 0.23 22:31:25 INFO - mozprofile == 0.28 22:31:25 INFO - mozrunner == 6.12 22:31:25 INFO - mozscreenshot == 0.1 22:31:25 INFO - mozsystemmonitor == 0.3 22:31:25 INFO - moztest == 0.7 22:31:25 INFO - mozversion == 1.4 22:31:25 INFO - psutil == 3.1.1 22:31:25 INFO - requests == 1.2.3 22:31:25 INFO - wsgiref == 0.1.2 22:31:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:25 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:31:25 INFO - Using env: {'DISPLAY': ':0', 22:31:25 INFO - 'HOME': '/home/cltbld', 22:31:25 INFO - 'LANG': 'en_US.UTF-8', 22:31:25 INFO - 'LANGUAGE': 'en_US:en', 22:31:25 INFO - 'LOGNAME': 'cltbld', 22:31:25 INFO - 'MAIL': '/var/mail/cltbld', 22:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:25 INFO - 'MOZ_NO_REMOTE': '1', 22:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:25 INFO - 'NO_EM_RESTART': '1', 22:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:25 INFO - 'PWD': '/builds/slave/test', 22:31:25 INFO - 'SHELL': '/bin/bash', 22:31:25 INFO - 'SHLVL': '1', 22:31:25 INFO - 'TERM': 'linux', 22:31:25 INFO - 'TMOUT': '86400', 22:31:25 INFO - 'USER': 'cltbld', 22:31:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:25 INFO - '_': '/tools/buildbot/bin/python'} 22:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:31:25 INFO - Cleaning up... 22:31:25 INFO - Return code: 0 22:31:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:31:25 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 0x7fd145204f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d80790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, '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': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:31:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:31:25 INFO - Using env: {'DISPLAY': ':0', 22:31:25 INFO - 'HOME': '/home/cltbld', 22:31:25 INFO - 'LANG': 'en_US.UTF-8', 22:31:25 INFO - 'LANGUAGE': 'en_US:en', 22:31:25 INFO - 'LOGNAME': 'cltbld', 22:31:25 INFO - 'MAIL': '/var/mail/cltbld', 22:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:25 INFO - 'MOZ_NO_REMOTE': '1', 22:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:25 INFO - 'NO_EM_RESTART': '1', 22:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:25 INFO - 'PWD': '/builds/slave/test', 22:31:25 INFO - 'SHELL': '/bin/bash', 22:31:25 INFO - 'SHLVL': '1', 22:31:25 INFO - 'TERM': 'linux', 22:31:25 INFO - 'TMOUT': '86400', 22:31:25 INFO - 'USER': 'cltbld', 22:31:25 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:25 INFO - '_': '/tools/buildbot/bin/python'} 22:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:31:25 INFO - Cleaning up... 22:31:25 INFO - Return code: 0 22:31:25 INFO - Running post-action listener: _resource_record_post_action 22:31:25 INFO - Running post-action listener: _start_resource_monitoring 22:31:25 INFO - Starting resource monitoring. 22:31:25 INFO - [mozharness: 2016-10-24 05:31:25.565576Z] Finished create-virtualenv step (success) 22:31:25 INFO - [mozharness: 2016-10-24 05:31:25.566087Z] Running install step. 22:31:25 INFO - Running pre-action listener: _resource_record_pre_action 22:31:25 INFO - Running main action method: install 22:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:31:25 INFO - Reading from file tmpfile_stdout 22:31:25 INFO - Detecting whether we're running mozinstall >=1.0... 22:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:31:25 INFO - Reading from file tmpfile_stdout 22:31:25 INFO - Output received: 22:31:25 INFO - Usage: mozinstall [options] installer 22:31:25 INFO - Options: 22:31:25 INFO - -h, --help show this help message and exit 22:31:25 INFO - -d DEST, --destination=DEST 22:31:25 INFO - Directory to install application into. [default: 22:31:25 INFO - "/builds/slave/test"] 22:31:25 INFO - --app=APP Application being installed. [default: firefox] 22:31:25 INFO - mkdir: /builds/slave/test/build/application 22:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:31:42 INFO - Reading from file tmpfile_stdout 22:31:42 INFO - Output received: 22:31:42 INFO - /builds/slave/test/build/application/firefox/firefox 22:31:42 INFO - Running post-action listener: _resource_record_post_action 22:31:42 INFO - [mozharness: 2016-10-24 05:31:42.756747Z] Finished install step (success) 22:31:42 INFO - [mozharness: 2016-10-24 05:31:42.756992Z] Running run-tests step. 22:31:42 INFO - Running pre-action listener: _resource_record_pre_action 22:31:42 INFO - Running main action method: run_tests 22:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:31:42 INFO - Python 2.7.3 22:31:42 INFO - Return code: 0 22:31:42 INFO - grabbing minidump binary from tooltool 22:31:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:31:42 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 0x1d7d930>, '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 0x1d7e830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d7ecc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:31:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:31:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:31:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:31:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:31:42 INFO - Return code: 0 22:31:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:31:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:31:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:31:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:31:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:31:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:31:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:31:42 INFO - Using env: {'DISPLAY': ':0', 22:31:42 INFO - 'HOME': '/home/cltbld', 22:31:42 INFO - 'LANG': 'en_US.UTF-8', 22:31:42 INFO - 'LANGUAGE': 'en_US:en', 22:31:42 INFO - 'LOGNAME': 'cltbld', 22:31:42 INFO - 'MAIL': '/var/mail/cltbld', 22:31:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:31:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:31:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:31:42 INFO - 'MOZ_NO_REMOTE': '1', 22:31:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:31:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:31:42 INFO - 'NO_EM_RESTART': '1', 22:31:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:31:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:31:42 INFO - 'PWD': '/builds/slave/test', 22:31:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:31:42 INFO - 'SHELL': '/bin/bash', 22:31:42 INFO - 'SHLVL': '1', 22:31:42 INFO - 'TERM': 'linux', 22:31:42 INFO - 'TMOUT': '86400', 22:31:42 INFO - 'USER': 'cltbld', 22:31:42 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886', 22:31:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:31:42 INFO - '_': '/tools/buildbot/bin/python'} 22:31:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:31:43 INFO - mozversion application_buildid: 20161023193001 22:31:43 INFO - mozversion application_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 22:31:43 INFO - mozversion application_display_name: Nightly 22:31:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:31:43 INFO - mozversion application_name: Firefox 22:31:43 INFO - mozversion application_remotingname: firefox 22:31:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:31:43 INFO - mozversion application_vendor: Mozilla 22:31:43 INFO - mozversion application_version: 52.0a1 22:31:43 INFO - mozversion platform_buildid: 20161023193001 22:31:43 INFO - mozversion platform_changeset: 215f9686117673a2c914ed207bc7da9bb8d741ad 22:31:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:31:43 INFO - mozversion platform_version: 52.0a1 22:31:43 INFO - using testdate: 1477287103 22:31:43 INFO - actual date: 1477287103 22:31:43 INFO - starting webserver on 'localhost:44490' 22:31:43 INFO - SUITE-START | Running 2 tests 22:31:43 INFO - TEST-START | dromaeo_css 22:31:43 INFO - operating with platform_type : linux_ 22:31:43 INFO - Initialising browser for dromaeo_css test... 22:31:43 INFO - TEST-INFO | started process 1613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfGzM9R/profile http://localhost:44490/getInfo.html) 22:31:47 INFO - PROCESS | 1613 | __metrics Screen width/height:1600/1200 22:31:47 INFO - PROCESS | 1613 | colorDepth:24 22:31:47 INFO - PROCESS | 1613 | Browser inner width/height: 1024/697 22:31:47 INFO - PROCESS | 1613 | __metrics 22:31:47 INFO - PROCESS | 1613 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:31:47 INFO - PROCESS | 1613 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:31:48 INFO - PROCESS | 1613 | console.error: PushService: 22:31:48 INFO - PROCESS | 1613 | stateChangeProcessEnqueue: Error transitioning state 22:31:48 INFO - PROCESS | 1613 | UnknownError 22:31:49 INFO - TEST-INFO | 1613: exit 0 22:31:49 INFO - Browser initialized. 22:31:49 INFO - Running cycle 1/1 for dromaeo_css test... 22:31:49 INFO - TEST-INFO | started process 1696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfGzM9R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:32:01 INFO - PROCESS | 1696 | RSS: Main: 171958272 22:32:01 INFO - PROCESS | 1696 | 22:32:52 INFO - PROCESS | 1696 | 1477287172981 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:35:03 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44490/tests/dromaeo/cssquery-ext.html) 22:35:05 INFO - PROCESS | 1696 | RSS: Main: 282402816 22:35:05 INFO - PROCESS | 1696 | 22:38:07 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-ext.html (next: http://localhost:44490/tests/dromaeo/cssquery-jquery.html) 22:38:08 INFO - PROCESS | 1696 | RSS: Main: 281710592 22:38:08 INFO - PROCESS | 1696 | 22:41:10 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44490/tests/dromaeo/cssquery-mootools.html) 22:41:11 INFO - PROCESS | 1696 | RSS: Main: 244690944 22:41:11 INFO - PROCESS | 1696 | 22:44:13 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44490/tests/dromaeo/cssquery-prototype.html) 22:44:13 INFO - PROCESS | 1696 | RSS: Main: 265891840 22:44:13 INFO - PROCESS | 1696 | 22:47:16 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44490/tests/dromaeo/cssquery-yui.html) 22:47:17 INFO - PROCESS | 1696 | RSS: Main: 218382336 22:47:17 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | Cycle 1(1): loaded http://localhost:44490/tests/dromaeo/cssquery-yui.html (next: http://localhost:44490/tests/dromaeo/cssquery-dojo.html) 22:50:20 INFO - PROCESS | 1696 | RSS: Main: 310661120 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | __start_tp_report 22:50:20 INFO - PROCESS | 1696 | _x_x_mozilla_page_load 22:50:20 INFO - PROCESS | 1696 | _x_x_mozilla_page_load_details 22:50:20 INFO - PROCESS | 1696 | |i|pagename|runs| 22:50:20 INFO - PROCESS | 1696 | |0;dojo.html;3072.815631062136;3090.4282707699076;3097.9225519362008;3123.562701221828;3148.4018036573048;5260.92108618371;5280.70956097415;5329;5340.118880384742;5380.7309634518315;5358.91961620576;5396.865078373043;5480.808171713991;5491.917621235674;5508.44915508449;4861.708297502161;4875.341828853113;4927.1377508935975;4954.851354459372;4961.875953101166;4933.481984391641;5033.723145227011;5043.445221025684;5049.924251136217;5094.261332106842;4792.329073929653;4959.280904268897;4966.975165124188;5056.216286475602;5087.720175390352;4832.033593281358;4862.708237505761;4891.6820406673805;4906.1168989581865;4907.681000734977;4839.419269687624;4840.1771698810935;4845.927311090337;4871.853844384674;4902.534259245366;5335.546478549328;5346.8395948121615;5349.893002139975;5355.116405793039;5359.383670877821;5518.365387980393;5549.861253468655;5660.037793575102;5701.77192912283;5746.138079288098;5743.425657434265;5755.136729490568;5775.740091695884;5819;5834.824955251307;5359.624826262124;5360.142377219665;5361.463853614647;5366.2755528004;5366.436524164985;5692.203091567184;5694.430556944272;5753.05074662676;5865.530757539386;5872.383399742966;5710.600257981984;5754.165645981309;5766.654000759968;5824.184614154022;5829.970850145722;5803.477687008105;5829.766809327664;5834.912476312858;5836.153757705194;5841.415858414124;5797.246357973437;5808.680522571215;5817.360090390053;5819.330776960619;5820.447057529528;5590.832275031674;5634.408387119375;5635.859103522363;5690.800821971211;5836.328822185418;4255.553166917491;4279.293916503742;4280.036991676848;4281.05816720321;4283.293256612624;3871.380579107329;3879.0884142226555;3884.2037382337116;3885.514310711161;3887.280853042196;5382.757775900094;5424.674519528762;5424.83725488236;5428.158635411518;5429.049916264638;5714.628549144292;5848.824535263949;5849.151872978395;5870.647761134345;5952.553558483044;5585.106382978704;5593.720313984366;5613.971930140405;5621.915671264935;5624.521915637135;4923.310736496894;4937.802487900443;4944.406671199479;4948.356713627278;5000.549950504472;5695.943040569541;6008.068749343771;6012.789552365646;6013.909791353133;6015.789447369233;5610.803621873215;5668.0931051033285;5682.346530149086;5716.056850619601;5722.2561067061015;5901.0558310670085;5907.822765317047;5950.34546199909;5956.731947062479;5970.64176149415;5662.348829884616;5670.149477578396;5672.688002159921;5675.858103547444;5675.971620141873;5535.225068490334;5601.187827765033;5681.801136960352;5730.942690573041;5764.971175144097;5687.829365119053;5688.40271771458;5689.146628005667;5727;5754.625949313281;5652.5477961762135;5654.604177707521;5657.12314591239;5660.29246344207;5661;5381.058314794974;5403.378611459575;5619.606627536196;5641.689707066151;5660.462256085665;5681.460261275172;5712.8571785704025;5729.770809167587;5754.395788442321;5801.709914504174;5541.806036788693;5557.221988921473;5561.5272701820795;5562;5577.358603760619 22:50:20 INFO - PROCESS | 1696 | |1;ext.html;604.7127614383168;606.5147881694645;647.7797548833398;654.3325807676167;658.9835254118649;32226.516602250955;42446.57553424465;42830.357544636856;42846;46117.76941115298;44240.77879610605;44713.329300060526;44760.3285950711;44776.3283550747;44803.77598112005;8755.387122901384;8937.195652391283;8952.8657070144;8956.955215223914;8959.059298773633;8168.224018718212;8373;10490.580376784957;10769.461526923662;10770.73073173173;10840.403777792219;10927.836082458769;10929.56281748729;10934.671959841218;10936.890631093707;10682.679519614425;10760.300580462244;10768.569257229701;10770.030697237247;10772.192085593533;10608.681739547827;10686.572537098507;10697.625583104555;10703.571857125706;10704.197185211062;40910.79544602288;41099.38350924738;41112.79443602763;41123;41181;3189.6650851660474;3358.9664103358896;3458.654134586546;3565.5721313442295;3995.2409042281874;30527.542086868714;31503.684963150532;31709.682903171135;33293.33413331744;33434.83282583596;4321.82712691492;4337.089211265638;4378.671599630041;4381.233284175289;4398.3402489626815;334285;339427;339562;339688;340235.67761062906;54725;55465.44534554683;55496.16755748667;55546.16680749792;55573;58837.41162588405;58867.41132588705;58870.70564647149;58905.70547147237;59058.704706476194;14236.572902812932;14491.420343186366;14590.19753913545;14877.107373557621;14885.106893586419;10314.742131446774;10805.297655652515;10851.728706782236;10860.782784344427;10865.674029779118;16094.51716448485;18267.54331141732;18532.444026679223;18897;18995.715064274045;6929.272426395156;6961.40828029613;7114.50198486101;7125.5368401053765;7213.422926165894;925.5788616179622;956.7801053656505;1069.5882085397022;1115.6931843743037;1154.7863645225493;31612.525812112377;31820.522692159637;32030.199245018594;32079.679203208136;32095.679043209737;1017.4302390661253;1055.9155267578728;1065.8028264771042;1104.5857803323754;1120.5181771838024;15990.281652119167;16294.837051629569;16859.409920652717;16883.577910552336;17123.828761712473;519235;794673;811321;811537;816050;57282.140767888515;57806.7109664449;57907;57917.710411447675;57937;1181.1082632612495;1228.588422878328;1270.3648175912044;1288.1047671867962;1334.5395838435725;28617.28456788597;31890.20274493156;32099.518507222878;33388;33568.49647255244;52313.738431307596;53065.7346713264;53161.734191330346;53199.46800531945;53224.73387633037;477695;481191;481337;482161;484126;26743.598846017714;27443.451130976868;27633.723662763918;27696.307592310353;27926.860365698853;45443.772781135885;46082.30876536922;46287.45419001853;46992.29511557398;47359.76320118377;38639.80680096582;39472;39743.80128099341;40570.1885962285;41563.960900976504;38738.4189237156;39077.41383879187;39540.80229598834;39668.40497392484;40644.59355406408;38252;38587.421188681634;38966.610333897435;39317.2136557273;39529.407058893565;33034;34953.47569786404;35801.46297805587;36068.45897311595;36173.63826361808;26420;26790.330241744112;27261.045863394687;27462.17613471599;27480 22:50:20 INFO - PROCESS | 1696 | |2;jquery.html;362.87662194853743;375.51746006381785;385.2449199568845;391.76102577427764;392.4407719000425;14056.929715351422;14868.851311486882;18514.351997680085;18694;18722.344717934873;17208.311667533282;17728.556786080353;17790.199441025154;17816.643667126682;17900.910495447504;4100.815463304159;5431.076716958107;5584.86037849053;5619.915701264464;5637.154426835987;4314.97842510787;6081.391860813927;6305.5901366411035;6364.459020983222;6473.41739243468;6189.845253868667;6301.306856245809;6375.872482550346;6403.231612206519;6409.294977552466;6342.460890824285;6350.9682451587905;6378.840528986766;6381.298057213703;6390.8721825563225;5394.163904594794;5457.617966742329;5551.805686800943;5559.722013899329;5562.110062390038;21758;21971.121155153774;22032.228871989402;22191;22515.21196758122;2101.0965284927474;2154.2890846020778;2200.471886747185;2251.8648881067024;2284.2804516577226;13260.46958121674;14029.64925876851;14035.929820350833;14431.49489767853;14590.562283131418;1668.1409074326732;1788.3533857705352;1842.1434033174637;1846.9445916622524;1910.503269150017;81856.59071704662;81928.59035704784;82088.58955705243;84870.57564712137;85995;9969.252306076929;10263.794724105626;10322.22583306255;10412.895871041344;10560.947195264129;14535.200563968872;14540.927295363455;14555.92722036383;14572.708545829024;14584.270786460635;6987.231404545496;7059.5764254144915;7274.59989700561;7334.4499162563025;7341.265873412723;6578.539502234893;6685.264620891698;6784.966075169593;7034.050403195503;7041.225465198824;4366.3013917773005;4455.420795296591;4486.237339652267;4587.72473651582;4608.5161058088415;1818.5271829324472;1822.8541716662635;1860.5813691919209;1878.1548303263587;1903.7620297462818;882.969096081634;922.9353945223904;932.7108596335158;942.7030485397078;969.6315400148039;17527.582121486445;17653.117344133;18584.628307434046;18927.337543185924;19117.426477205427;902.3277658144724;937.8171256605033;939.6852054561798;974.444566597047;975.4391225045485;9837.409755414697;9854.950725246472;10139.442330671884;10359.274850760374;10386.701662292213;444073;453068;455529;461251;461771;89132.10867891238;90197;90761;90905;90967;983.6999715086968;1030.283952652899;1053.899879511445;1056.8890266522058;1058.6136060337922;16215.270312836186;16517.256723447234;17106.48680539586;17201.397951072155;17568.297268109134;38560.03599910025;38676.6132338673;40760.59239407568;41436;45322.546774533155;52006;52841.735791320796;53314.46685533095;57698;58151.70924145352;10721.571137154739;11367.147463940073;11384.14618903569;11801.29192248468;11867.169298149047;8456.492610443147;8493.193146651058;8799.692010779592;8983.236424903957;13512.932435338154;8233.876491852507;8423.4945903246;8429.241368276884;8700.434471759316;8995.100489950952;8239.464434811718;8321.209485098905;8331.791705207419;8435.53604551731;8691.348148888732;8154.347652187929;8349.666013359398;8639.481631102153;8728.08355122703;8856.114388561093;11660.88339116598;11960.342181180118;11978.880211197777;12038.939805300917;12164.08769342285;11919.46362413676;12001.519939202333;12111.697207569881;12347.19743216688;12772.297523636289 22:50:20 INFO - PROCESS | 1696 | |3;mootools.html;1297.1049975516887;1487.5834766265446;1495.409313321238;1641.8276081011493;1781.234069350179;7911.406644501664;8837.337199710024;9162.90837091629;9192.402493837895;9411;9163.679271225508;9246.583903724331;9260.259179265659;9266.860997085032;9273.489958052305;2324.070371851256;2324.267855625481;2328.895199716017;2346.636271377939;2354.482013956935;2250.8874556272203;2296.9310920672406;2322.094383190557;2335.1593426366494;2339.836211465198;2248.5053288276554;2264.377296243538;2266.0595852721185;2282.5092605089903;2284.4403121235237;2202.7466841313294;2203.9889800551055;2209.7569267380495;2210.1822325739417;2210.4142402263415;2184.355615093535;2192.5395666909967;2199.3182113544694;2201.5266717655622;2207.889605519734;9059.81880362389;9138.31462640298;9179.678711245142;9243.861342079874;9550.856737148948;5264.15773476242;5419;5428.104362780136;5431.348238211401;5433.6196466247375;3475.7566970312087;3497.440409534462;3537.0626783902285;3568.7323450741037;3575.803330816804;1034.6120204923154;1086.0931122512711;1089.9073578745738;1103.100972707241;1109.2235435195396;12862.356882155947;12985.740285194242;12990.350482475838;13021.348932553334;13025.218486890722;4396.714213576107;4470.307102399133;4646.581807637262;4648.465426475998;4681.822952327184;10026.598936042477;10066;10075.294729369021;10091.7477063074;10094.49527523621;5212.166053431432;5224.895502090013;5227.503387178212;5232.3982741985155;5279.260903473517;4392.1874453226255;4526.049529598821;4803.3188384066;4825;4916.950830491649;3771.0949372150485;3801.2777572261734;3836.2519308734527;3870.283997460479;3882.495275614152;1100.1528822806395;1103.1119948441421;1105.535675016479;1111.7331840358256;1112.9443527823576;906.1301150895083;938.990610093904;941.6986564299496;971.9757006074905;974.5906719177963;5889.5877288590245;5921.61509501881;6093.573449858556;6183.752649894044;6265.843353916189;440.8479074719217;441.52977079410323;442.4668274728944;443.67611643500425;450.5111953530456;3944.3294639911283;4136.586341365839;4155.958440415558;4156.06488540076;4169.499660040815;11223.38271395081;11553.30680159193;11658.41707914601;11662.708432289091;11674.474648640831;11512.654620361403;11517.308961462339;11520.654380368602;11524.366159861289;11532.59635912739;8071.4753541019645;8459.830803383898;8486;8568.657253709907;8576.271016963628;6088.695565221809;6230.7819226326865;6245.6252624842655;6252.374762523893;6281.309056003927;10558.078393881722;10734.892651073389;10810.67567972962;10829.079528240189;10852.077573406348;10920.726981825517;11167.162462815484;11189.496472658913;11197.272177308449;11203.831942520706;7361.521501102411;7538.283863033003;7554.131274903457;7781.494202876795;7937.166597507409;7980.162082981436;8608.053114157563;8798.296136309207;8900.154485323885;8901.599428025884;7762.495437796525;8041.236082572146;8071.757847264592;8099.676012959416;8210.137935516923;7914.089879663912;7922.366210703015;8016.438849280495;8046.517208967247;8119.634616442156;6860.828479288058;6975.581465111906;7047.048648432396;7047.894281585883;7050.083489146378;5203.375594928632;5257.395399529103;5285.339332583427;5317.654352467077;5406.864828379322;5287.206918962186;5299.2316114164005;5307.4692530746615;5379.462053794589;5456.399796022355 22:50:20 INFO - PROCESS | 1696 | |4;prototype.html;323.6278279978026;329.1245287535156;336.71211114497106;337.5965720963448;338.6207447658623;5224.503672151149;5246.7638956246965;5247.658902171365;5261.684298942061;5263.657862238952;4940.061388336214;5031.566003688949;5064.873378165547;5133.255677926699;5205.37535495741;4064.796760161995;4074.3684728866924;4078.775667338295;4080.489938757655;4106.342985122381;4064.6586626413214;4081.4898137732785;4092.7544347339253;4096.877093687194;4097.467329247209;3822.7706337619693;3910.4720862683457;4076.694247931402;4081.3673880548563;4087.6729861611;4024.8591299304676;4104.671626269891;4106.876793696194;4115.629593336599;4119.711620186588;3618.240169564395;3624.5469316335457;3625.583057948343;3628.310620982005;3644.726645501598;4845.588125009378;5089.57491902268;5098.821541246039;5102.821401250939;5110.66780659256;4606.746628935407;4623.537646235383;4638.76806159694;4639.512851150648;4640.422256432796;5445.9727701361635;5451.010381210869;5456.181572764078;5458.727063646802;5476.698781567012;3801.163743976321;3888.4336338016774;3906.257811015926;3908.745931514471;3911.882643520699;5585.692786896719;5602.719864006784;5624.746886390122;5639.323281206241;5655.824920198014;4000.139969906484;4000.399940009022;4007.6192761687594;4011.197760447864;4028.113814960704;4032;4071.5114186297833;4077.7757223352405;4079.4288799568085;4104.302268614343;4659.347691323217;4663.300504924288;4666.673332866734;4671.906561868812;4683.531646835358;5489.972550137303;5511.751971161308;5517.2551705520045;5522.30971128609;5578.721063946787;1409.5481653896502;1410.9647258818406;1416.5608661315025;1422.3954819201797;1424.7862820576997;939.68050862707;968.914815406749;970.2819913264275;984.9113579777854;987.3335498538457;5261.3686357637325;5271.5255626992985;5286.735663216824;5289.3652761668845;5373.731313434313;5580.302462192226;5615.831525054255;5626.774929002876;5672.376038635747;5683.829485115453;5246.711430871339;5312.468753124656;5319.28189694394;5350.946490535122;5356.946430535645;2990.20759498733;2990.685977972325;2997.621094296648;2999.100269919016;3001.864916078782;5562.221289019541;5729.942700572941;5746.367899531048;5794.82615521535;5807.186993820868;5668.376478587352;5710.200571919934;5710.771569137271;5713.457656253149;5739.540836733051;5756.280464941882;5757.539396848242;5758.971205143947;5773.624714393635;5805.738741756547;5376.8118115865755;5545.972270138624;5586.916196257141;5592.412796656293;5644.858878527905;5504;5517.834464966058;5522.199281104297;5538.698405874536;5544.200178909625;5539.196816461671;5753.338366087954;5791.160281759206;5802.274715660543;5809.506191973561;4017.3371393720877;4039.7576145431367;4043.6142675821407;4047.2917239482385;4051.331530297468;5430.5384042356845;5539.224508568885;5574.386817449996;5690;5700.686462244616;5518.972405138109;5579.7079722963645;5598.1602759586385;5599.664020158803;5600.0879542636785;5468.91796623059;5524.861878453071;5533;5589.785873123052;5596.944030559643;5291.497307755757;5499;5557.166425036277;5566.914451681883;5589.441055894378;5610.18652295423;5615.129654903497;5618.438156184349;5624.100143977108;5645.548356131581;5160.690358578498;5377.784888604412;5431.212474191141;5456.317960254968;5478.698671573102 22:50:20 INFO - PROCESS | 1696 | |5;yui.html;686.4714170089031;695.1901035293882;723.6920156602207;733.6625152429883;868.6872725818704;15272.770908436369;16380.262888170031;16506.25721842517;16522.66954660909;16551.668966620677;15100;15624.687506249868;15807.446739364124;16083.758743618855;16114.194290285468;805.1787177079376;829.7718127514921;832.7834762961613;833.5748768128268;834.04501845387;659.4757168051386;675.530506298123;793.2900054451262;814.8696208606594;821.5153059694779;859.9828003439927;861.2464093917822;861.4142383179435;861.7070196133313;861.8147098373848;850.0606829453488;850.5577099908076;850.8808766772656;851.0213254757017;851.0383266908385;504.1857400298528;517.1208944793865;518.2847670215117;518.4841083122279;604.0637012630406;14608.123512589278;14901.478448254258;14915.328810203458;14930.850691493055;14974.326155323037;12009.759804803942;12090;12368.735083532238;12389;12832.55086071983;12428.813567796491;12460.439280232411;12748.681282968;12829.935850320782;13278.46886124554;4410.669199810027;4508.729476231437;4513.706609070433;4543.5456454354635;4685.203515402356;81256.59371703104;82863.5856820712;83396;83437;83611;10952.616658416917;10976.835347469794;11035.227534072537;11155;11342.432878356049;20906;21090.367288981353;21135.577288454144;21194.682079768816;21225.68161477579;7793.18171591986;8003.399745019147;8118.594070296462;8126.75619731409;8169.754907352789;4619.46876109245;4717.976410117996;4726.243800991825;4809;4830.106430310404;3531.8587256509963;3621.8189090545366;3646.124930016777;3661.4141737322434;3663.7069034477713;605.6729366142322;626.0515319291277;629.9118123462719;641.8748344072955;657.1850904877917;653.5359894474881;657.8026592022375;664.259350823828;687.1136234257751;690.4303949241895;8676.60955257015;8927.866082008775;9207.631694732136;9219.631214751469;9271;568.4628026514987;576.0926540698417;630.3979699387074;660.0923729871427;674.2583158525681;3996.2806694795217;4421.9336709949375;4433.224185767478;4533.795979180944;4573.085382923428;115476;120277;120740;120929;121201.39399302947;62017;89284.5535772317;100841;103427;103509.48245258725;82355;83486.58256708799;85096.94193388634;87742;88131;8301.709440169565;8382.83234335322;8469.74590762278;8483.703070392507;8522.957385213282;52916.73541632267;53439.7328013373;53497;53710.85156593849;54677.72661136669;71154.93267600886;77940;80553.70863807407;81331.59334203528;82669.17330826855;10203.481855993683;10398.948005259925;10523.73690657709;10888.346699198073;11003.249187703073;12655.746885062063;12674.556390526288;12721.93639031836;12898.935505322788;12988.675283117998;13305.534306299232;13338.26639534796;13617.523386681418;13999.110564791226;14651.12093274407;10875.619353322596;11516.459527176272;12404.317762523147;13591.52429664957;13670.93164534171;12908.612741617766;13647.249401283025;13852.653683657989;13963.930477705457;14199.787003195166;10640.390193762705;10669.839952400565;10807.783844323227;11262.718432038937;11300.660980170607;10281.434521101412;10543.789124217627;11177.944110279397;11216.270942388719;11803 22:50:20 INFO - PROCESS | 1696 | __end_tp_report 22:50:20 INFO - PROCESS | 1696 | __start_cc_report 22:50:20 INFO - PROCESS | 1696 | _x_x_mozilla_cycle_collect,1287 22:50:20 INFO - PROCESS | 1696 | __end_cc_report 22:50:20 INFO - PROCESS | 1696 | __startTimestamp1477288220444__endTimestamp 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | ------- Summary: start ------- 22:50:20 INFO - PROCESS | 1696 | Number of tests: 6 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#0] dojo.html Cycles:165 Average:5352.20 Median:5593.72 stddev:616.72 (11.0%) stddev-sans-first:592.12 22:50:20 INFO - PROCESS | 1696 | Values: 3072.8 3090.4 3097.9 3123.6 3148.4 5260.9 5280.7 5329.0 5340.1 5380.7 5358.9 5396.9 5480.8 5491.9 5508.4 4861.7 4875.3 4927.1 4954.9 4961.9 4933.5 5033.7 5043.4 5049.9 5094.3 4792.3 4959.3 4967.0 5056.2 5087.7 4832.0 4862.7 4891.7 4906.1 4907.7 4839.4 4840.2 4845.9 4871.9 4902.5 5335.5 5346.8 5349.9 5355.1 5359.4 5518.4 5549.9 5660.0 5701.8 5746.1 5743.4 5755.1 5775.7 5819.0 5834.8 5359.6 5360.1 5361.5 5366.3 5366.4 5692.2 5694.4 5753.1 5865.5 5872.4 5710.6 5754.2 5766.7 5824.2 5830.0 5803.5 5829.8 5834.9 5836.2 5841.4 5797.2 5808.7 5817.4 5819.3 5820.4 5590.8 5634.4 5635.9 5690.8 5836.3 4255.6 4279.3 4280.0 4281.1 4283.3 3871.4 3879.1 3884.2 3885.5 3887.3 5382.8 5424.7 5424.8 5428.2 5429.0 5714.6 5848.8 5849.2 5870.6 5952.6 5585.1 5593.7 5614.0 5621.9 5624.5 4923.3 4937.8 4944.4 4948.4 5000.5 5695.9 6008.1 6012.8 6013.9 6015.8 5610.8 5668.1 5682.3 5716.1 5722.3 5901.1 5907.8 5950.3 5956.7 5970.6 5662.3 5670.1 5672.7 5675.9 5676.0 5535.2 5601.2 5681.8 5730.9 5765.0 5687.8 5688.4 5689.1 5727.0 5754.6 5652.5 5654.6 5657.1 5660.3 5661.0 5381.1 5403.4 5619.6 5641.7 5660.5 5681.5 5712.9 5729.8 5754.4 5801.7 5541.8 5557.2 5561.5 5562.0 5577.4 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#1] ext.html Cycles:180 Average:66757.10 Median:37212.82 stddev:149014.99 (400.4%) stddev-sans-first:149350.24 22:50:20 INFO - PROCESS | 1696 | Values: 604.7 606.5 647.8 654.3 659.0 32226.5 42446.6 42830.4 42846.0 46117.8 44240.8 44713.3 44760.3 44776.3 44803.8 8755.4 8937.2 8952.9 8957.0 8959.1 8168.2 8373.0 10490.6 10769.5 10770.7 10840.4 10927.8 10929.6 10934.7 10936.9 10682.7 10760.3 10768.6 10770.0 10772.2 10608.7 10686.6 10697.6 10703.6 10704.2 40910.8 41099.4 41112.8 41123.0 41181.0 3189.7 3359.0 3458.7 3565.6 3995.2 30527.5 31503.7 31709.7 33293.3 33434.8 4321.8 4337.1 4378.7 4381.2 4398.3 334285.0 339427.0 339562.0 339688.0 340235.7 54725.0 55465.4 55496.2 55546.2 55573.0 58837.4 58867.4 58870.7 58905.7 59058.7 14236.6 14491.4 14590.2 14877.1 14885.1 10314.7 10805.3 10851.7 10860.8 10865.7 16094.5 18267.5 18532.4 18897.0 18995.7 6929.3 6961.4 7114.5 7125.5 7213.4 925.6 956.8 1069.6 1115.7 1154.8 31612.5 31820.5 32030.2 32079.7 32095.7 1017.4 1055.9 1065.8 1104.6 1120.5 15990.3 16294.8 16859.4 16883.6 17123.8 519235.0 794673.0 811321.0 811537.0 816050.0 57282.1 57806.7 57907.0 57917.7 57937.0 1181.1 1228.6 1270.4 1288.1 1334.5 28617.3 31890.2 32099.5 33388.0 33568.5 52313.7 53065.7 53161.7 53199.5 53224.7 477695.0 481191.0 481337.0 482161.0 484126.0 26743.6 27443.5 27633.7 27696.3 27926.9 45443.8 46082.3 46287.5 46992.3 47359.8 38639.8 39472.0 39743.8 40570.2 41564.0 38738.4 39077.4 39540.8 39668.4 40644.6 38252.0 38587.4 38966.6 39317.2 39529.4 33034.0 34953.5 35801.5 36068.5 36173.6 26420.0 26790.3 27261.0 27462.2 27480.0 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#2] jquery.html Cycles:180 Average:27599.20 Median:4470.83 stddev:75295.85 (1684.2%) stddev-sans-first:75479.30 22:50:20 INFO - PROCESS | 1696 | Values: 362.9 375.5 385.2 391.8 392.4 14056.9 14868.9 18514.4 18694.0 18722.3 17208.3 17728.6 17790.2 17816.6 17900.9 4100.8 5431.1 5584.9 5619.9 5637.2 4315.0 6081.4 6305.6 6364.5 6473.4 6189.8 6301.3 6375.9 6403.2 6409.3 6342.5 6351.0 6378.8 6381.3 6390.9 5394.2 5457.6 5551.8 5559.7 5562.1 21758.0 21971.1 22032.2 22191.0 22515.2 2101.1 2154.3 2200.5 2251.9 2284.3 13260.5 14029.6 14035.9 14431.5 14590.6 1668.1 1788.4 1842.1 1846.9 1910.5 81856.6 81928.6 82088.6 84870.6 85995.0 9969.3 10263.8 10322.2 10412.9 10560.9 14535.2 14540.9 14555.9 14572.7 14584.3 6987.2 7059.6 7274.6 7334.4 7341.3 6578.5 6685.3 6785.0 7034.1 7041.2 4366.3 4455.4 4486.2 4587.7 4608.5 1818.5 1822.9 1860.6 1878.2 1903.8 883.0 922.9 932.7 942.7 969.6 17527.6 17653.1 18584.6 18927.3 19117.4 902.3 937.8 939.7 974.4 975.4 9837.4 9855.0 10139.4 10359.3 10386.7 444073.0 453068.0 455529.0 461251.0 461771.0 89132.1 90197.0 90761.0 90905.0 90967.0 983.7 1030.3 1053.9 1056.9 1058.6 16215.3 16517.3 17106.5 17201.4 17568.3 38560.0 38676.6 40760.6 41436.0 45322.5 52006.0 52841.7 53314.5 57698.0 58151.7 10721.6 11367.1 11384.1 11801.3 11867.2 8456.5 8493.2 8799.7 8983.2 13512.9 8233.9 8423.5 8429.2 8700.4 8995.1 8239.5 8321.2 8331.8 8435.5 8691.3 8154.3 8349.7 8639.5 8728.1 8856.1 11660.9 11960.3 11978.9 12038.9 12164.1 11919.5 12001.5 12111.7 12347.2 12772.3 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#3] mootools.html Cycles:180 Average:5941.03 Median:2456.99 stddev:3523.89 (143.4%) stddev-sans-first:3516.50 22:50:20 INFO - PROCESS | 1696 | Values: 1297.1 1487.6 1495.4 1641.8 1781.2 7911.4 8837.3 9162.9 9192.4 9411.0 9163.7 9246.6 9260.3 9266.9 9273.5 2324.1 2324.3 2328.9 2346.6 2354.5 2250.9 2296.9 2322.1 2335.2 2339.8 2248.5 2264.4 2266.1 2282.5 2284.4 2202.7 2204.0 2209.8 2210.2 2210.4 2184.4 2192.5 2199.3 2201.5 2207.9 9059.8 9138.3 9179.7 9243.9 9550.9 5264.2 5419.0 5428.1 5431.3 5433.6 3475.8 3497.4 3537.1 3568.7 3575.8 1034.6 1086.1 1089.9 1103.1 1109.2 12862.4 12985.7 12990.4 13021.3 13025.2 4396.7 4470.3 4646.6 4648.5 4681.8 10026.6 10066.0 10075.3 10091.7 10094.5 5212.2 5224.9 5227.5 5232.4 5279.3 4392.2 4526.0 4803.3 4825.0 4917.0 3771.1 3801.3 3836.3 3870.3 3882.5 1100.2 1103.1 1105.5 1111.7 1112.9 906.1 939.0 941.7 972.0 974.6 5889.6 5921.6 6093.6 6183.8 6265.8 440.8 441.5 442.5 443.7 450.5 3944.3 4136.6 4156.0 4156.1 4169.5 11223.4 11553.3 11658.4 11662.7 11674.5 11512.7 11517.3 11520.7 11524.4 11532.6 8071.5 8459.8 8486.0 8568.7 8576.3 6088.7 6230.8 6245.6 6252.4 6281.3 10558.1 10734.9 10810.7 10829.1 10852.1 10920.7 11167.2 11189.5 11197.3 11203.8 7361.5 7538.3 7554.1 7781.5 7937.2 7980.2 8608.1 8798.3 8900.2 8901.6 7762.5 8041.2 8071.8 8099.7 8210.1 7914.1 7922.4 8016.4 8046.5 8119.6 6860.8 6975.6 7047.0 7047.9 7050.1 5203.4 5257.4 5285.3 5317.7 5406.9 5287.2 5299.2 5307.5 5379.5 5456.4 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#4] prototype.html Cycles:180 Average:4621.31 Median:5290.43 stddev:1352.69 (25.6%) stddev-sans-first:1317.46 22:50:20 INFO - PROCESS | 1696 | Values: 323.6 329.1 336.7 337.6 338.6 5224.5 5246.8 5247.7 5261.7 5263.7 4940.1 5031.6 5064.9 5133.3 5205.4 4064.8 4074.4 4078.8 4080.5 4106.3 4064.7 4081.5 4092.8 4096.9 4097.5 3822.8 3910.5 4076.7 4081.4 4087.7 4024.9 4104.7 4106.9 4115.6 4119.7 3618.2 3624.5 3625.6 3628.3 3644.7 4845.6 5089.6 5098.8 5102.8 5110.7 4606.7 4623.5 4638.8 4639.5 4640.4 5446.0 5451.0 5456.2 5458.7 5476.7 3801.2 3888.4 3906.3 3908.7 3911.9 5585.7 5602.7 5624.7 5639.3 5655.8 4000.1 4000.4 4007.6 4011.2 4028.1 4032.0 4071.5 4077.8 4079.4 4104.3 4659.3 4663.3 4666.7 4671.9 4683.5 5490.0 5511.8 5517.3 5522.3 5578.7 1409.5 1411.0 1416.6 1422.4 1424.8 939.7 968.9 970.3 984.9 987.3 5261.4 5271.5 5286.7 5289.4 5373.7 5580.3 5615.8 5626.8 5672.4 5683.8 5246.7 5312.5 5319.3 5350.9 5356.9 2990.2 2990.7 2997.6 2999.1 3001.9 5562.2 5729.9 5746.4 5794.8 5807.2 5668.4 5710.2 5710.8 5713.5 5739.5 5756.3 5757.5 5759.0 5773.6 5805.7 5376.8 5546.0 5586.9 5592.4 5644.9 5504.0 5517.8 5522.2 5538.7 5544.2 5539.2 5753.3 5791.2 5802.3 5809.5 4017.3 4039.8 4043.6 4047.3 4051.3 5430.5 5539.2 5574.4 5690.0 5700.7 5519.0 5579.7 5598.2 5599.7 5600.1 5468.9 5524.9 5533.0 5589.8 5596.9 5291.5 5499.0 5557.2 5566.9 5589.4 5610.2 5615.1 5618.4 5624.1 5645.5 5160.7 5377.8 5431.2 5456.3 5478.7 22:50:20 INFO - PROCESS | 1696 | 22:50:20 INFO - PROCESS | 1696 | [#5] yui.html Cycles:180 Average:20858.30 Median:4558.32 stddev:30627.82 (671.9%) stddev-sans-first:30676.29 22:50:20 INFO - PROCESS | 1696 | Values: 686.5 695.2 723.7 733.7 868.7 15272.8 16380.3 16506.3 16522.7 16551.7 15100.0 15624.7 15807.4 16083.8 16114.2 805.2 829.8 832.8 833.6 834.0 659.5 675.5 793.3 814.9 821.5 860.0 861.2 861.4 861.7 861.8 850.1 850.6 850.9 851.0 851.0 504.2 517.1 518.3 518.5 604.1 14608.1 14901.5 14915.3 14930.9 14974.3 12009.8 12090.0 12368.7 12389.0 12832.6 12428.8 12460.4 12748.7 12829.9 13278.5 4410.7 4508.7 4513.7 4543.5 4685.2 81256.6 82863.6 83396.0 83437.0 83611.0 10952.6 10976.8 11035.2 11155.0 11342.4 20906.0 21090.4 21135.6 21194.7 21225.7 7793.2 8003.4 8118.6 8126.8 8169.8 4619.5 4718.0 4726.2 4809.0 4830.1 3531.9 3621.8 3646.1 3661.4 3663.7 605.7 626.1 629.9 641.9 657.2 653.5 657.8 664.3 687.1 690.4 8676.6 8927.9 9207.6 9219.6 9271.0 568.5 576.1 630.4 660.1 674.3 3996.3 4421.9 4433.2 4533.8 4573.1 115476.0 120277.0 120740.0 120929.0 121201.4 62017.0 89284.6 100841.0 103427.0 103509.5 82355.0 83486.6 85096.9 87742.0 88131.0 8301.7 8382.8 8469.7 8483.7 8523.0 52916.7 53439.7 53497.0 53710.9 54677.7 71154.9 77940.0 80553.7 81331.6 82669.2 10203.5 10398.9 10523.7 10888.3 11003.2 12655.7 12674.6 12721.9 12898.9 12988.7 13305.5 13338.3 13617.5 13999.1 14651.1 10875.6 11516.5 12404.3 13591.5 13670.9 12908.6 13647.2 13852.7 13963.9 14199.8 10640.4 10669.8 10807.8 11262.7 11300.7 10281.4 10543.8 11177.9 11216.3 11803.0 22:50:20 INFO - PROCESS | 1696 | -------- Summary: end -------- 22:50:20 INFO - PROCESS | 1696 | 22:50:21 INFO - TEST-INFO | 1696: exit 0 22:50:21 INFO - TEST-OK | dromaeo_css | took 1118396ms 22:50:21 INFO - TEST-START | kraken 22:50:21 INFO - operating with platform_type : linux_ 22:50:21 INFO - Initialising browser for kraken test... 22:50:21 INFO - TEST-INFO | started process 2004 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF0PB9N/profile http://localhost:44490/getInfo.html) 22:50:25 INFO - PROCESS | 2004 | __metrics Screen width/height:1600/1200 22:50:25 INFO - PROCESS | 2004 | colorDepth:24 22:50:25 INFO - PROCESS | 2004 | Browser inner width/height: 1024/697 22:50:25 INFO - PROCESS | 2004 | __metrics 22:50:26 INFO - PROCESS | 2004 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:50:26 INFO - PROCESS | 2004 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:50:26 INFO - PROCESS | 2004 | console.error: PushService: 22:50:26 INFO - PROCESS | 2004 | stateChangeProcessEnqueue: Error transitioning state 22:50:26 INFO - PROCESS | 2004 | UnknownError 22:50:27 INFO - TEST-INFO | 2004: exit 0 22:50:27 INFO - Browser initialized. 22:50:27 INFO - Running cycle 1/1 for kraken test... 22:50:27 INFO - TEST-INFO | started process 2088 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF0PB9N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:39 INFO - PROCESS | 2088 | RSS: Main: 172244992 22:50:39 INFO - PROCESS | 2088 | 22:50:42 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44490/tests/kraken/driver.html?testName=audio-beat-detection) 22:50:43 INFO - PROCESS | 2088 | RSS: Main: 229629952 22:50:43 INFO - PROCESS | 2088 | 22:50:45 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44490/tests/kraken/driver.html?testName=audio-dft) 22:50:46 INFO - PROCESS | 2088 | RSS: Main: 216588288 22:50:46 INFO - PROCESS | 2088 | 22:50:49 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44490/tests/kraken/driver.html?testName=audio-fft) 22:50:49 INFO - PROCESS | 2088 | RSS: Main: 297562112 22:50:49 INFO - PROCESS | 2088 | 22:50:52 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44490/tests/kraken/driver.html?testName=audio-oscillator) 22:50:52 INFO - PROCESS | 2088 | RSS: Main: 206876672 22:50:52 INFO - PROCESS | 2088 | 22:50:54 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:50:55 INFO - PROCESS | 2088 | RSS: Main: 211984384 22:50:55 INFO - PROCESS | 2088 | 22:50:58 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44490/tests/kraken/driver.html?testName=imaging-darkroom) 22:50:58 INFO - PROCESS | 2088 | RSS: Main: 248086528 22:50:58 INFO - PROCESS | 2088 | 22:51:02 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44490/tests/kraken/driver.html?testName=imaging-desaturate) 22:51:02 INFO - PROCESS | 2088 | RSS: Main: 246902784 22:51:02 INFO - PROCESS | 2088 | 22:51:05 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44490/tests/kraken/driver.html?testName=json-parse-financial) 22:51:06 INFO - PROCESS | 2088 | RSS: Main: 246538240 22:51:06 INFO - PROCESS | 2088 | 22:51:07 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:51:08 INFO - PROCESS | 2088 | RSS: Main: 209059840 22:51:08 INFO - PROCESS | 2088 | 22:51:09 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:51:10 INFO - PROCESS | 2088 | RSS: Main: 215711744 22:51:10 INFO - PROCESS | 2088 | 22:51:12 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:51:13 INFO - PROCESS | 2088 | RSS: Main: 225759232 22:51:13 INFO - PROCESS | 2088 | 22:51:15 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:51:16 INFO - PROCESS | 2088 | RSS: Main: 234057728 22:51:16 INFO - PROCESS | 2088 | 22:51:19 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:51:19 INFO - PROCESS | 2088 | RSS: Main: 231665664 22:51:19 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | Cycle 1(1): loaded http://localhost:44490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44490/tests/kraken/driver.html?testName=ai-astar) 22:51:21 INFO - PROCESS | 2088 | RSS: Main: 282546176 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | __start_tp_report 22:51:21 INFO - PROCESS | 2088 | _x_x_mozilla_page_load 22:51:21 INFO - PROCESS | 2088 | _x_x_mozilla_page_load_details 22:51:21 INFO - PROCESS | 2088 | |i|pagename|runs| 22:51:21 INFO - PROCESS | 2088 | |0;ai-astar;106;111;111;110;111;112;111;112;110;110 22:51:21 INFO - PROCESS | 2088 | |1;audio-beat-detection;150;122;121;167;124;122;125;123;153;122 22:51:21 INFO - PROCESS | 2088 | |2;audio-dft;161;161;162;164;161;162;163;163;161;161 22:51:21 INFO - PROCESS | 2088 | |3;audio-fft;74;78;76;103;75;76;75;77;97;76 22:51:21 INFO - PROCESS | 2088 | |4;audio-oscillator;83;83;125;84;85;84;111;83;83;114 22:51:21 INFO - PROCESS | 2088 | |5;imaging-gaussian-blur;116;117;116;117;117;118;118;117;116;117 22:51:21 INFO - PROCESS | 2088 | |6;imaging-darkroom;147;145;145;146;145;146;145;146;146;146 22:51:21 INFO - PROCESS | 2088 | |7;imaging-desaturate;84;85;84;84;84;85;84;84;85;84 22:51:21 INFO - PROCESS | 2088 | |8;json-parse-financial;52;52;51;52;51;54;53;53;53;53 22:51:21 INFO - PROCESS | 2088 | |9;json-stringify-tinderbox;61;65;59;60;60;60;59;60;59;59 22:51:21 INFO - PROCESS | 2088 | |10;stanford-crypto-aes;80;80;79;88;85;80;80;80;79;81 22:51:21 INFO - PROCESS | 2088 | |11;stanford-crypto-ccm;140;130;130;132;142;131;129;133;175;131 22:51:21 INFO - PROCESS | 2088 | |12;stanford-crypto-pbkdf2;165;165;167;169;169;169;171;172;169;165 22:51:21 INFO - PROCESS | 2088 | |13;stanford-crypto-sha256-iterative;66;73;67;66;66;65;71;65;65;64 22:51:21 INFO - PROCESS | 2088 | __end_tp_report 22:51:21 INFO - PROCESS | 2088 | __start_cc_report 22:51:21 INFO - PROCESS | 2088 | _x_x_mozilla_cycle_collect,1705 22:51:21 INFO - PROCESS | 2088 | __end_cc_report 22:51:21 INFO - PROCESS | 2088 | __startTimestamp1477288281759__endTimestamp 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | ------- Summary: start ------- 22:51:21 INFO - PROCESS | 2088 | Number of tests: 14 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.71 (1.5%) stddev-sans-first:0.78 22:51:21 INFO - PROCESS | 2088 | Values: 106.0 111.0 111.0 110.0 111.0 112.0 111.0 112.0 110.0 110.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:124.50 stddev:16.99 (13.6%) stddev-sans-first:16.85 22:51:21 INFO - PROCESS | 2088 | Values: 150.0 122.0 121.0 167.0 124.0 122.0 125.0 123.0 153.0 122.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 22:51:21 INFO - PROCESS | 2088 | Values: 161.0 161.0 162.0 164.0 161.0 162.0 163.0 163.0 161.0 161.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#3] audio-fft Cycles:10 Average:80.70 Median:76.00 stddev:10.33 (13.6%) stddev-sans-first:10.67 22:51:21 INFO - PROCESS | 2088 | Values: 74.0 78.0 76.0 103.0 75.0 76.0 75.0 77.0 97.0 76.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:83.00 stddev:16.37 (19.7%) stddev-sans-first:16.92 22:51:21 INFO - PROCESS | 2088 | Values: 83.0 83.0 125.0 84.0 85.0 84.0 111.0 83.0 83.0 114.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 22:51:21 INFO - PROCESS | 2088 | Values: 116.0 117.0 116.0 117.0 117.0 118.0 118.0 117.0 116.0 117.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 22:51:21 INFO - PROCESS | 2088 | Values: 147.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 22:51:21 INFO - PROCESS | 2088 | Values: 84.0 85.0 84.0 84.0 84.0 85.0 84.0 84.0 85.0 84.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 22:51:21 INFO - PROCESS | 2088 | Values: 52.0 52.0 51.0 52.0 51.0 54.0 53.0 53.0 53.0 53.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:1.81 (3.0%) stddev-sans-first:1.90 22:51:21 INFO - PROCESS | 2088 | Values: 61.0 65.0 59.0 60.0 60.0 60.0 59.0 60.0 59.0 59.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:80.00 stddev:2.94 (3.7%) stddev-sans-first:3.08 22:51:21 INFO - PROCESS | 2088 | Values: 80.0 80.0 79.0 88.0 85.0 80.0 80.0 80.0 79.0 81.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#11] stanford-crypto-ccm Cycles:10 Average:137.30 Median:132.50 stddev:13.95 (10.5%) stddev-sans-first:14.76 22:51:21 INFO - PROCESS | 2088 | Values: 140.0 130.0 130.0 132.0 142.0 131.0 129.0 133.0 175.0 131.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:169.00 stddev:2.51 (1.5%) stddev-sans-first:2.40 22:51:21 INFO - PROCESS | 2088 | Values: 165.0 165.0 167.0 169.0 169.0 169.0 171.0 172.0 169.0 165.0 22:51:21 INFO - PROCESS | 2088 | 22:51:21 INFO - PROCESS | 2088 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.00 stddev:2.90 (4.4%) stddev-sans-first:3.06 22:51:21 INFO - PROCESS | 2088 | Values: 66.0 73.0 67.0 66.0 66.0 65.0 71.0 65.0 65.0 64.0 22:51:21 INFO - PROCESS | 2088 | -------- Summary: end -------- 22:51:21 INFO - PROCESS | 2088 | 22:51:22 INFO - TEST-INFO | 2088: exit 0 22:51:22 INFO - TEST-OK | kraken | took 61518ms 22:51:22 INFO - SUITE-END | took 1179s 22:51:23 INFO - Completed test suite (00:19:40) 22:51:23 INFO - javascript benchmark 22:51:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3072.815631062136, 3090.4282707699076, 3097.9225519362008, 3123.562701221828, 3148.4018036573048, 5260.92108618371, 5280.70956097415, 5329.0, 5340.118880384742, 5380.7309634518315, 5358.91961620576, 5396.865078373043, 5480.808171713991, 5491.917621235674, 5508.44915508449, 4861.708297502161, 4875.341828853113, 4927.1377508935975, 4954.851354459372, 4961.875953101166, 4933.481984391641, 5033.723145227011, 5043.445221025684, 5049.924251136217, 5094.261332106842, 4792.329073929653, 4959.280904268897, 4966.975165124188, 5056.216286475602, 5087.720175390352, 4832.033593281358, 4862.708237505761, 4891.6820406673805, 4906.1168989581865, 4907.681000734977, 4839.419269687624, 4840.1771698810935, 4845.927311090337, 4871.853844384674, 4902.534259245366, 5335.546478549328, 5346.8395948121615, 5349.893002139975, 5355.116405793039, 5359.383670877821, 5518.365387980393, 5549.861253468655, 5660.037793575102, 5701.77192912283, 5746.138079288098, 5743.425657434265, 5755.136729490568, 5775.740091695884, 5819.0, 5834.824955251307, 5359.624826262124, 5360.142377219665, 5361.463853614647, 5366.2755528004, 5366.436524164985, 5692.203091567184, 5694.430556944272, 5753.05074662676, 5865.530757539386, 5872.383399742966, 5710.600257981984, 5754.165645981309, 5766.654000759968, 5824.184614154022, 5829.970850145722, 5803.477687008105, 5829.766809327664, 5834.912476312858, 5836.153757705194, 5841.415858414124, 5797.246357973437, 5808.680522571215, 5817.360090390053, 5819.330776960619, 5820.447057529528, 5590.832275031674, 5634.408387119375, 5635.859103522363, 5690.800821971211, 5836.328822185418, 4255.553166917491, 4279.293916503742, 4280.036991676848, 4281.05816720321, 4283.293256612624, 3871.380579107329, 3879.0884142226555, 3884.2037382337116, 3885.514310711161, 3887.280853042196, 5382.757775900094, 5424.674519528762, 5424.83725488236, 5428.158635411518, 5429.049916264638, 5714.628549144292, 5848.824535263949, 5849.151872978395, 5870.647761134345, 5952.553558483044, 5585.106382978704, 5593.720313984366, 5613.971930140405, 5621.915671264935, 5624.521915637135, 4923.310736496894, 4937.802487900443, 4944.406671199479, 4948.356713627278, 5000.549950504472, 5695.943040569541, 6008.068749343771, 6012.789552365646, 6013.909791353133, 6015.789447369233, 5610.803621873215, 5668.0931051033285, 5682.346530149086, 5716.056850619601, 5722.2561067061015, 5901.0558310670085, 5907.822765317047, 5950.34546199909, 5956.731947062479, 5970.64176149415, 5662.348829884616, 5670.149477578396, 5672.688002159921, 5675.858103547444, 5675.971620141873, 5535.225068490334, 5601.187827765033, 5681.801136960352, 5730.942690573041, 5764.971175144097, 5687.829365119053, 5688.40271771458, 5689.146628005667, 5727.0, 5754.625949313281, 5652.5477961762135, 5654.604177707521, 5657.12314591239, 5660.29246344207, 5661.0, 5381.058314794974, 5403.378611459575, 5619.606627536196, 5641.689707066151, 5660.462256085665, 5681.460261275172, 5712.8571785704025, 5729.770809167587, 5754.395788442321, 5801.709914504174, 5541.806036788693, 5557.221988921473, 5561.5272701820795, 5562.0, 5577.358603760619], "value": 5309.75815180101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.7127614383168, 606.5147881694645, 647.7797548833398, 654.3325807676167, 658.9835254118649, 32226.516602250955, 42446.57553424465, 42830.357544636856, 42846.0, 46117.76941115298, 44240.77879610605, 44713.329300060526, 44760.3285950711, 44776.3283550747, 44803.77598112005, 8755.387122901384, 8937.195652391283, 8952.8657070144, 8956.955215223914, 8959.059298773633, 8168.224018718212, 8373.0, 10490.580376784957, 10769.461526923662, 10770.73073173173, 10840.403777792219, 10927.836082458769, 10929.56281748729, 10934.671959841218, 10936.890631093707, 10682.679519614425, 10760.300580462244, 10768.569257229701, 10770.030697237247, 10772.192085593533, 10608.681739547827, 10686.572537098507, 10697.625583104555, 10703.571857125706, 10704.197185211062, 40910.79544602288, 41099.38350924738, 41112.79443602763, 41123.0, 41181.0, 3189.6650851660474, 3358.9664103358896, 3458.654134586546, 3565.5721313442295, 3995.2409042281874, 30527.542086868714, 31503.684963150532, 31709.682903171135, 33293.33413331744, 33434.83282583596, 4321.82712691492, 4337.089211265638, 4378.671599630041, 4381.233284175289, 4398.3402489626815, 334285.0, 339427.0, 339562.0, 339688.0, 340235.67761062906, 54725.0, 55465.44534554683, 55496.16755748667, 55546.16680749792, 55573.0, 58837.41162588405, 58867.41132588705, 58870.70564647149, 58905.70547147237, 59058.704706476194, 14236.572902812932, 14491.420343186366, 14590.19753913545, 14877.107373557621, 14885.106893586419, 10314.742131446774, 10805.297655652515, 10851.728706782236, 10860.782784344427, 10865.674029779118, 16094.51716448485, 18267.54331141732, 18532.444026679223, 18897.0, 18995.715064274045, 6929.272426395156, 6961.40828029613, 7114.50198486101, 7125.5368401053765, 7213.422926165894, 925.5788616179622, 956.7801053656505, 1069.5882085397022, 1115.6931843743037, 1154.7863645225493, 31612.525812112377, 31820.522692159637, 32030.199245018594, 32079.679203208136, 32095.679043209737, 1017.4302390661253, 1055.9155267578728, 1065.8028264771042, 1104.5857803323754, 1120.5181771838024, 15990.281652119167, 16294.837051629569, 16859.409920652717, 16883.577910552336, 17123.828761712473, 519235.0, 794673.0, 811321.0, 811537.0, 816050.0, 57282.140767888515, 57806.7109664449, 57907.0, 57917.710411447675, 57937.0, 1181.1082632612495, 1228.588422878328, 1270.3648175912044, 1288.1047671867962, 1334.5395838435725, 28617.28456788597, 31890.20274493156, 32099.518507222878, 33388.0, 33568.49647255244, 52313.738431307596, 53065.7346713264, 53161.734191330346, 53199.46800531945, 53224.73387633037, 477695.0, 481191.0, 481337.0, 482161.0, 484126.0, 26743.598846017714, 27443.451130976868, 27633.723662763918, 27696.307592310353, 27926.860365698853, 45443.772781135885, 46082.30876536922, 46287.45419001853, 46992.29511557398, 47359.76320118377, 38639.80680096582, 39472.0, 39743.80128099341, 40570.1885962285, 41563.960900976504, 38738.4189237156, 39077.41383879187, 39540.80229598834, 39668.40497392484, 40644.59355406408, 38252.0, 38587.421188681634, 38966.610333897435, 39317.2136557273, 39529.407058893565, 33034.0, 34953.47569786404, 35801.46297805587, 36068.45897311595, 36173.63826361808, 26420.0, 26790.330241744112, 27261.045863394687, 27462.17613471599, 27480.0], "value": 20281.84604539775, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.87662194853743, 375.51746006381785, 385.2449199568845, 391.76102577427764, 392.4407719000425, 14056.929715351422, 14868.851311486882, 18514.351997680085, 18694.0, 18722.344717934873, 17208.311667533282, 17728.556786080353, 17790.199441025154, 17816.643667126682, 17900.910495447504, 4100.815463304159, 5431.076716958107, 5584.86037849053, 5619.915701264464, 5637.154426835987, 4314.97842510787, 6081.391860813927, 6305.5901366411035, 6364.459020983222, 6473.41739243468, 6189.845253868667, 6301.306856245809, 6375.872482550346, 6403.231612206519, 6409.294977552466, 6342.460890824285, 6350.9682451587905, 6378.840528986766, 6381.298057213703, 6390.8721825563225, 5394.163904594794, 5457.617966742329, 5551.805686800943, 5559.722013899329, 5562.110062390038, 21758.0, 21971.121155153774, 22032.228871989402, 22191.0, 22515.21196758122, 2101.0965284927474, 2154.2890846020778, 2200.471886747185, 2251.8648881067024, 2284.2804516577226, 13260.46958121674, 14029.64925876851, 14035.929820350833, 14431.49489767853, 14590.562283131418, 1668.1409074326732, 1788.3533857705352, 1842.1434033174637, 1846.9445916622524, 1910.503269150017, 81856.59071704662, 81928.59035704784, 82088.58955705243, 84870.57564712137, 85995.0, 9969.252306076929, 10263.794724105626, 10322.22583306255, 10412.895871041344, 10560.947195264129, 14535.200563968872, 14540.927295363455, 14555.92722036383, 14572.708545829024, 14584.270786460635, 6987.231404545496, 7059.5764254144915, 7274.59989700561, 7334.4499162563025, 7341.265873412723, 6578.539502234893, 6685.264620891698, 6784.966075169593, 7034.050403195503, 7041.225465198824, 4366.3013917773005, 4455.420795296591, 4486.237339652267, 4587.72473651582, 4608.5161058088415, 1818.5271829324472, 1822.8541716662635, 1860.5813691919209, 1878.1548303263587, 1903.7620297462818, 882.969096081634, 922.9353945223904, 932.7108596335158, 942.7030485397078, 969.6315400148039, 17527.582121486445, 17653.117344133, 18584.628307434046, 18927.337543185924, 19117.426477205427, 902.3277658144724, 937.8171256605033, 939.6852054561798, 974.444566597047, 975.4391225045485, 9837.409755414697, 9854.950725246472, 10139.442330671884, 10359.274850760374, 10386.701662292213, 444073.0, 453068.0, 455529.0, 461251.0, 461771.0, 89132.10867891238, 90197.0, 90761.0, 90905.0, 90967.0, 983.6999715086968, 1030.283952652899, 1053.899879511445, 1056.8890266522058, 1058.6136060337922, 16215.270312836186, 16517.256723447234, 17106.48680539586, 17201.397951072155, 17568.297268109134, 38560.03599910025, 38676.6132338673, 40760.59239407568, 41436.0, 45322.546774533155, 52006.0, 52841.735791320796, 53314.46685533095, 57698.0, 58151.70924145352, 10721.571137154739, 11367.147463940073, 11384.14618903569, 11801.29192248468, 11867.169298149047, 8456.492610443147, 8493.193146651058, 8799.692010779592, 8983.236424903957, 13512.932435338154, 8233.876491852507, 8423.4945903246, 8429.241368276884, 8700.434471759316, 8995.100489950952, 8239.464434811718, 8321.209485098905, 8331.791705207419, 8435.53604551731, 8691.348148888732, 8154.347652187929, 8349.666013359398, 8639.481631102153, 8728.08355122703, 8856.114388561093, 11660.88339116598, 11960.342181180118, 11978.880211197777, 12038.939805300917, 12164.08769342285, 11919.46362413676, 12001.519939202333, 12111.697207569881, 12347.19743216688, 12772.297523636289], "value": 8869.256714855457, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.1049975516887, 1487.5834766265446, 1495.409313321238, 1641.8276081011493, 1781.234069350179, 7911.406644501664, 8837.337199710024, 9162.90837091629, 9192.402493837895, 9411.0, 9163.679271225508, 9246.583903724331, 9260.259179265659, 9266.860997085032, 9273.489958052305, 2324.070371851256, 2324.267855625481, 2328.895199716017, 2346.636271377939, 2354.482013956935, 2250.8874556272203, 2296.9310920672406, 2322.094383190557, 2335.1593426366494, 2339.836211465198, 2248.5053288276554, 2264.377296243538, 2266.0595852721185, 2282.5092605089903, 2284.4403121235237, 2202.7466841313294, 2203.9889800551055, 2209.7569267380495, 2210.1822325739417, 2210.4142402263415, 2184.355615093535, 2192.5395666909967, 2199.3182113544694, 2201.5266717655622, 2207.889605519734, 9059.81880362389, 9138.31462640298, 9179.678711245142, 9243.861342079874, 9550.856737148948, 5264.15773476242, 5419.0, 5428.104362780136, 5431.348238211401, 5433.6196466247375, 3475.7566970312087, 3497.440409534462, 3537.0626783902285, 3568.7323450741037, 3575.803330816804, 1034.6120204923154, 1086.0931122512711, 1089.9073578745738, 1103.100972707241, 1109.2235435195396, 12862.356882155947, 12985.740285194242, 12990.350482475838, 13021.348932553334, 13025.218486890722, 4396.714213576107, 4470.307102399133, 4646.581807637262, 4648.465426475998, 4681.822952327184, 10026.598936042477, 10066.0, 10075.294729369021, 10091.7477063074, 10094.49527523621, 5212.166053431432, 5224.895502090013, 5227.503387178212, 5232.3982741985155, 5279.260903473517, 4392.1874453226255, 4526.049529598821, 4803.3188384066, 4825.0, 4916.950830491649, 3771.0949372150485, 3801.2777572261734, 3836.2519308734527, 3870.283997460479, 3882.495275614152, 1100.1528822806395, 1103.1119948441421, 1105.535675016479, 1111.7331840358256, 1112.9443527823576, 906.1301150895083, 938.990610093904, 941.6986564299496, 971.9757006074905, 974.5906719177963, 5889.5877288590245, 5921.61509501881, 6093.573449858556, 6183.752649894044, 6265.843353916189, 440.8479074719217, 441.52977079410323, 442.4668274728944, 443.67611643500425, 450.5111953530456, 3944.3294639911283, 4136.586341365839, 4155.958440415558, 4156.06488540076, 4169.499660040815, 11223.38271395081, 11553.30680159193, 11658.41707914601, 11662.708432289091, 11674.474648640831, 11512.654620361403, 11517.308961462339, 11520.654380368602, 11524.366159861289, 11532.59635912739, 8071.4753541019645, 8459.830803383898, 8486.0, 8568.657253709907, 8576.271016963628, 6088.695565221809, 6230.7819226326865, 6245.6252624842655, 6252.374762523893, 6281.309056003927, 10558.078393881722, 10734.892651073389, 10810.67567972962, 10829.079528240189, 10852.077573406348, 10920.726981825517, 11167.162462815484, 11189.496472658913, 11197.272177308449, 11203.831942520706, 7361.521501102411, 7538.283863033003, 7554.131274903457, 7781.494202876795, 7937.166597507409, 7980.162082981436, 8608.053114157563, 8798.296136309207, 8900.154485323885, 8901.599428025884, 7762.495437796525, 8041.236082572146, 8071.757847264592, 8099.676012959416, 8210.137935516923, 7914.089879663912, 7922.366210703015, 8016.438849280495, 8046.517208967247, 8119.634616442156, 6860.828479288058, 6975.581465111906, 7047.048648432396, 7047.894281585883, 7050.083489146378, 5203.375594928632, 5257.395399529103, 5285.339332583427, 5317.654352467077, 5406.864828379322, 5287.206918962186, 5299.2316114164005, 5307.4692530746615, 5379.462053794589, 5456.399796022355], "value": 4588.481638844611, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.6278279978026, 329.1245287535156, 336.71211114497106, 337.5965720963448, 338.6207447658623, 5224.503672151149, 5246.7638956246965, 5247.658902171365, 5261.684298942061, 5263.657862238952, 4940.061388336214, 5031.566003688949, 5064.873378165547, 5133.255677926699, 5205.37535495741, 4064.796760161995, 4074.3684728866924, 4078.775667338295, 4080.489938757655, 4106.342985122381, 4064.6586626413214, 4081.4898137732785, 4092.7544347339253, 4096.877093687194, 4097.467329247209, 3822.7706337619693, 3910.4720862683457, 4076.694247931402, 4081.3673880548563, 4087.6729861611, 4024.8591299304676, 4104.671626269891, 4106.876793696194, 4115.629593336599, 4119.711620186588, 3618.240169564395, 3624.5469316335457, 3625.583057948343, 3628.310620982005, 3644.726645501598, 4845.588125009378, 5089.57491902268, 5098.821541246039, 5102.821401250939, 5110.66780659256, 4606.746628935407, 4623.537646235383, 4638.76806159694, 4639.512851150648, 4640.422256432796, 5445.9727701361635, 5451.010381210869, 5456.181572764078, 5458.727063646802, 5476.698781567012, 3801.163743976321, 3888.4336338016774, 3906.257811015926, 3908.745931514471, 3911.882643520699, 5585.692786896719, 5602.719864006784, 5624.746886390122, 5639.323281206241, 5655.824920198014, 4000.139969906484, 4000.399940009022, 4007.6192761687594, 4011.197760447864, 4028.113814960704, 4032.0, 4071.5114186297833, 4077.7757223352405, 4079.4288799568085, 4104.302268614343, 4659.347691323217, 4663.300504924288, 4666.673332866734, 4671.906561868812, 4683.531646835358, 5489.972550137303, 5511.751971161308, 5517.2551705520045, 5522.30971128609, 5578.721063946787, 1409.5481653896502, 1410.9647258818406, 1416.5608661315025, 1422.3954819201797, 1424.7862820576997, 939.68050862707, 968.914815406749, 970.2819913264275, 984.9113579777854, 987.3335498538457, 5261.3686357637325, 5271.5255626992985, 5286.735663216824, 5289.3652761668845, 5373.731313434313, 5580.302462192226, 5615.831525054255, 5626.774929002876, 5672.376038635747, 5683.829485115453, 5246.711430871339, 5312.468753124656, 5319.28189694394, 5350.946490535122, 5356.946430535645, 2990.20759498733, 2990.685977972325, 2997.621094296648, 2999.100269919016, 3001.864916078782, 5562.221289019541, 5729.942700572941, 5746.367899531048, 5794.82615521535, 5807.186993820868, 5668.376478587352, 5710.200571919934, 5710.771569137271, 5713.457656253149, 5739.540836733051, 5756.280464941882, 5757.539396848242, 5758.971205143947, 5773.624714393635, 5805.738741756547, 5376.8118115865755, 5545.972270138624, 5586.916196257141, 5592.412796656293, 5644.858878527905, 5504.0, 5517.834464966058, 5522.199281104297, 5538.698405874536, 5544.200178909625, 5539.196816461671, 5753.338366087954, 5791.160281759206, 5802.274715660543, 5809.506191973561, 4017.3371393720877, 4039.7576145431367, 4043.6142675821407, 4047.2917239482385, 4051.331530297468, 5430.5384042356845, 5539.224508568885, 5574.386817449996, 5690.0, 5700.686462244616, 5518.972405138109, 5579.7079722963645, 5598.1602759586385, 5599.664020158803, 5600.0879542636785, 5468.91796623059, 5524.861878453071, 5533.0, 5589.785873123052, 5596.944030559643, 5291.497307755757, 5499.0, 5557.166425036277, 5566.914451681883, 5589.441055894378, 5610.18652295423, 5615.129654903497, 5618.438156184349, 5624.100143977108, 5645.548356131581, 5160.690358578498, 5377.784888604412, 5431.212474191141, 5456.317960254968, 5478.698671573102], "value": 4194.378931395696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.4714170089031, 695.1901035293882, 723.6920156602207, 733.6625152429883, 868.6872725818704, 15272.770908436369, 16380.262888170031, 16506.25721842517, 16522.66954660909, 16551.668966620677, 15100.0, 15624.687506249868, 15807.446739364124, 16083.758743618855, 16114.194290285468, 805.1787177079376, 829.7718127514921, 832.7834762961613, 833.5748768128268, 834.04501845387, 659.4757168051386, 675.530506298123, 793.2900054451262, 814.8696208606594, 821.5153059694779, 859.9828003439927, 861.2464093917822, 861.4142383179435, 861.7070196133313, 861.8147098373848, 850.0606829453488, 850.5577099908076, 850.8808766772656, 851.0213254757017, 851.0383266908385, 504.1857400298528, 517.1208944793865, 518.2847670215117, 518.4841083122279, 604.0637012630406, 14608.123512589278, 14901.478448254258, 14915.328810203458, 14930.850691493055, 14974.326155323037, 12009.759804803942, 12090.0, 12368.735083532238, 12389.0, 12832.55086071983, 12428.813567796491, 12460.439280232411, 12748.681282968, 12829.935850320782, 13278.46886124554, 4410.669199810027, 4508.729476231437, 4513.706609070433, 4543.5456454354635, 4685.203515402356, 81256.59371703104, 82863.5856820712, 83396.0, 83437.0, 83611.0, 10952.616658416917, 10976.835347469794, 11035.227534072537, 11155.0, 11342.432878356049, 20906.0, 21090.367288981353, 21135.577288454144, 21194.682079768816, 21225.68161477579, 7793.18171591986, 8003.399745019147, 8118.594070296462, 8126.75619731409, 8169.754907352789, 4619.46876109245, 4717.976410117996, 4726.243800991825, 4809.0, 4830.106430310404, 3531.8587256509963, 3621.8189090545366, 3646.124930016777, 3661.4141737322434, 3663.7069034477713, 605.6729366142322, 626.0515319291277, 629.9118123462719, 641.8748344072955, 657.1850904877917, 653.5359894474881, 657.8026592022375, 664.259350823828, 687.1136234257751, 690.4303949241895, 8676.60955257015, 8927.866082008775, 9207.631694732136, 9219.631214751469, 9271.0, 568.4628026514987, 576.0926540698417, 630.3979699387074, 660.0923729871427, 674.2583158525681, 3996.2806694795217, 4421.9336709949375, 4433.224185767478, 4533.795979180944, 4573.085382923428, 115476.0, 120277.0, 120740.0, 120929.0, 121201.39399302947, 62017.0, 89284.5535772317, 100841.0, 103427.0, 103509.48245258725, 82355.0, 83486.58256708799, 85096.94193388634, 87742.0, 88131.0, 8301.709440169565, 8382.83234335322, 8469.74590762278, 8483.703070392507, 8522.957385213282, 52916.73541632267, 53439.7328013373, 53497.0, 53710.85156593849, 54677.72661136669, 71154.93267600886, 77940.0, 80553.70863807407, 81331.59334203528, 82669.17330826855, 10203.481855993683, 10398.948005259925, 10523.73690657709, 10888.346699198073, 11003.249187703073, 12655.746885062063, 12674.556390526288, 12721.93639031836, 12898.935505322788, 12988.675283117998, 13305.534306299232, 13338.26639534796, 13617.523386681418, 13999.110564791226, 14651.12093274407, 10875.619353322596, 11516.459527176272, 12404.317762523147, 13591.52429664957, 13670.93164534171, 12908.612741617766, 13647.249401283025, 13852.653683657989, 13963.930477705457, 14199.787003195166, 10640.390193762705, 10669.839952400565, 10807.783844323227, 11262.718432038937, 11300.660980170607, 10281.434521101412, 10543.789124217627, 11177.944110279397, 11216.270942388719, 11803.0], "value": 7379.862597254026, "unit": "score"}], "name": "dromaeo_css", "value": 7168.30834914064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 111.0, 111.0, 110.0, 111.0, 112.0, 111.0, 112.0, 110.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 121.0, 167.0, 124.0, 122.0, 125.0, 123.0, 153.0, 122.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 164.0, 161.0, 162.0, 163.0, 163.0, 161.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 76.0, 103.0, 75.0, 76.0, 75.0, 77.0, 97.0, 76.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 125.0, 84.0, 85.0, 84.0, 111.0, 83.0, 83.0, 114.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 51.0, 52.0, 51.0, 54.0, 53.0, 53.0, 53.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 59.0, 60.0, 60.0, 60.0, 59.0, 60.0, 59.0, 59.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 88.0, 85.0, 80.0, 80.0, 80.0, 79.0, 81.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 130.0, 130.0, 132.0, 142.0, 131.0, 129.0, 133.0, 175.0, 131.0], "value": 137.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 167.0, 169.0, 169.0, 169.0, 171.0, 172.0, 169.0, 165.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 67.0, 66.0, 66.0, 65.0, 71.0, 65.0, 65.0, 64.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1492.2999999999997, "alertThreshold": 2.0}]} 22:51:23 INFO - Return code: 0 22:51:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:51:23 INFO - # TBPL SUCCESS # 22:51:23 INFO - Running post-action listener: _resource_record_post_action 22:51:23 INFO - [mozharness: 2016-10-24 05:51:23.553807Z] Finished run-tests step (success) 22:51:23 INFO - Running post-run listener: _resource_record_post_run 22:51:23 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 390680576; Read time: 16; Write time: 220936 22:51:23 INFO - TinderboxPrint: CPU usage
13.0% 22:51:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:51:23 INFO - TinderboxPrint: I/O write bytes / time
390,680,576 / 220,936 22:51:23 INFO - TinderboxPrint: CPU idle
8,304.8 (86.9%) 22:51:23 INFO - TinderboxPrint: CPU user
1,196.7 (12.5%) 22:51:23 INFO - TinderboxPrint: Swap in / out
0 / 0 22:51:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103391232; Read time: 0; Write time: 69748 22:51:23 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 286785536; Read time: 0; Write time: 151136 22:51:24 INFO - Running post-run listener: _upload_blobber_files 22:51:24 INFO - Blob upload gear active. 22:51:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:51:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:51:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:51:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:27 INFO - (blobuploader) - INFO - Done attempting. 22:51:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:51:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:51:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:28 INFO - (blobuploader) - INFO - Done attempting. 22:51:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:51:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:51:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:51:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:51:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:51:29 INFO - (blobuploader) - INFO - Done attempting. 22:51:29 INFO - (blobuploader) - INFO - Iteration through files over. 22:51:29 INFO - Return code: 0 22:51:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:51:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:51:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69b626945ccfdce6d02a45e316261805df3a73ceea13652c1720128e19eb398da986c39c5fbf4a33a7f5ca14396d57914764a7203b77ebc6fe10687e2a84762b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e9399f5679b18c5da492577e1afa73a9f2bd74e95aabd74eb98bd9d9e86f77566d98ea2c2c3c7c7bdd2abadeceb7ebd6e77d51895d5ea25ec5e13dcc5fa3bf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/287a221a4e9af841c34740afc3263eb0a0cd144a98e043317023e3cbe6f728dff079e05c76b8661a16ef9a59247362fa8f175e203d6606a3d372ae2101eb0e80"} 22:51:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:51:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:51:29 INFO - Contents: 22:51:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69b626945ccfdce6d02a45e316261805df3a73ceea13652c1720128e19eb398da986c39c5fbf4a33a7f5ca14396d57914764a7203b77ebc6fe10687e2a84762b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e9399f5679b18c5da492577e1afa73a9f2bd74e95aabd74eb98bd9d9e86f77566d98ea2c2c3c7c7bdd2abadeceb7ebd6e77d51895d5ea25ec5e13dcc5fa3bf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/287a221a4e9af841c34740afc3263eb0a0cd144a98e043317023e3cbe6f728dff079e05c76b8661a16ef9a59247362fa8f175e203d6606a3d372ae2101eb0e80"} 22:51:29 INFO - Running post-run listener: copy_logs_to_upload_dir 22:51:29 INFO - Copying logs to upload dir... 22:51:29 INFO - mkdir: /builds/slave/test/build/upload/logs 22:51:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.423769 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-10-23 22:51:29.137046) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 22:51:29.140737) ========= 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=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69b626945ccfdce6d02a45e316261805df3a73ceea13652c1720128e19eb398da986c39c5fbf4a33a7f5ca14396d57914764a7203b77ebc6fe10687e2a84762b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e9399f5679b18c5da492577e1afa73a9f2bd74e95aabd74eb98bd9d9e86f77566d98ea2c2c3c7c7bdd2abadeceb7ebd6e77d51895d5ea25ec5e13dcc5fa3bf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/287a221a4e9af841c34740afc3263eb0a0cd144a98e043317023e3cbe6f728dff079e05c76b8661a16ef9a59247362fa8f175e203d6606a3d372ae2101eb0e80"} build_url:https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/OZFt94NOSLC3OVSSMb7TOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/69b626945ccfdce6d02a45e316261805df3a73ceea13652c1720128e19eb398da986c39c5fbf4a33a7f5ca14396d57914764a7203b77ebc6fe10687e2a84762b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2e9399f5679b18c5da492577e1afa73a9f2bd74e95aabd74eb98bd9d9e86f77566d98ea2c2c3c7c7bdd2abadeceb7ebd6e77d51895d5ea25ec5e13dcc5fa3bf9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/287a221a4e9af841c34740afc3263eb0a0cd144a98e043317023e3cbe6f728dff079e05c76b8661a16ef9a59247362fa8f175e203d6606a3d372ae2101eb0e80"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-23 22:51:29.166226) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:51:29.166568) ========= 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=8887e36bb8e381bfd44124ac000001a8-1477283683.717752-771444886 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-23 22:51:29.216201) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 22:51:29.216526) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-23 22:51:29.216828) ========= ========= Total master_lag: 0.65 =========